[23:12:06.931] New invocation is queued and will start shortly
[23:12:09.722] Starting the invocation (attempt 1)
[23:12:09.750] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/7d1b8cd475374e0046119131957027877a0ca92c"
[23:12:09.750] Popped gitiles commit info from properties and tags
[23:12:09.750] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:12:09.751] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:12:09.751] Buildbucket request:
{
"requestId": "8886535528425226304",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "coverage"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8886535528425226304",
"job": "pigweed/pigweed.dev.ci-coverage",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "4a2ab500597e72d1d87777be23a644b9b343a702"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@4a2ab500597e72d1d87777be23a644b9b343a702",
"title": "4a2ab500597e72d1d87777be23a644b9b343a702",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/4a2ab500597e72d1d87777be23a644b9b343a702"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "8af514db2c62802bcd15b63d0b6ff04dd2c29207"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@8af514db2c62802bcd15b63d0b6ff04dd2c29207",
"title": "8af514db2c62802bcd15b63d0b6ff04dd2c29207",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/8af514db2c62802bcd15b63d0b6ff04dd2c29207"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "a13163722ab8fb678c1786596b8dc1507cf202e7"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@a13163722ab8fb678c1786596b8dc1507cf202e7",
"title": "a13163722ab8fb678c1786596b8dc1507cf202e7",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/a13163722ab8fb678c1786596b8dc1507cf202e7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "7d1b8cd475374e0046119131957027877a0ca92c"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@7d1b8cd475374e0046119131957027877a0ca92c",
"title": "7d1b8cd475374e0046119131957027877a0ca92c",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/7d1b8cd475374e0046119131957027877a0ca92c"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "7d1b8cd475374e0046119131957027877a0ca92c",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8886535528425226304"
},
{
"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"
}
}
[23:12:10.208] Scheduled build:
{
"id": "8721105464707606897",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "coverage"
},
"createdBy": "project:pigweed",
"createTime": "2025-03-06T23:12:09.846310333Z",
"updateTime": "2025-03-06T23:12:09.846310333Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "7d1b8cd475374e0046119131957027877a0ca92c",
"ref": "refs/heads/main"
}
}
}
[23:12:10.208] Task URL: https://cr-buildbucket.appspot.com/build/8721105464707606897
[23:12:10.208] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:2:0) after 9m5s
[23:12:11.260] Received PubSub notification, asking Buildbucket for the build status
[23:12:11.327] Build status: SCHEDULED
[23:21:15.236] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:2:0)
[23:21:15.261] Build status: SCHEDULED
[23:21:15.261] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:4:0) after 7m19s
[23:28:34.238] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:4:0)
[23:28:34.273] Build status: SCHEDULED
[23:28:34.274] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:5:0) after 6m57s
[23:35:31.432] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:5:0)
[23:35:31.459] Build status: SCHEDULED
[23:35:31.459] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:6:0) after 8m0s
[23:43:31.535] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:6:0)
[23:43:31.555] Build status: SCHEDULED
[23:43:31.555] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:7:0) after 6m34s
[23:50:05.537] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:7:0)
[23:50:05.555] Build status: SCHEDULED
[23:50:05.555] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:8:0) after 1m41s
[23:51:46.628] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:8:0)
[23:51:46.668] Build status: SCHEDULED
[23:51:46.668] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:9:0) after 4m13s
[23:55:59.744] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:9:0)
[23:55:59.772] Build status: SCHEDULED
[23:55:59.772] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:10:0) after 3m13s
[23:59:12.796] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:10:0)
[23:59:12.938] Build status: SCHEDULED
[23:59:12.938] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:11:0) after 5m55s
[00:05:08.029] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:11:0)
[00:05:08.051] Build status: SCHEDULED
[00:05:08.051] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:12:0) after 7m46s
[00:12:54.116] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:12:0)
[00:12:54.142] Build status: SCHEDULED
[00:12:54.142] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:13:0) after 9m1s
[00:21:55.273] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:13:0)
[00:21:55.310] Build status: SCHEDULED
[00:21:55.310] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:14:0) after 2m47s
[00:24:42.328] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:14:0)
[00:24:42.346] Build status: SCHEDULED
[00:24:42.346] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:15:0) after 6m56s
[00:31:38.364] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:15:0)
[00:31:38.395] Build status: SCHEDULED
[00:31:38.395] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:16:0) after 9m7s
[00:40:45.582] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:16:0)
[00:40:45.600] Build status: SCHEDULED
[00:40:45.600] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:17:0) after 7m4s
[00:47:49.653] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:17:0)
[00:47:49.682] Build status: SCHEDULED
[00:47:49.683] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:18:0) after 8m42s
[00:56:31.759] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:18:0)
[00:56:31.776] Build status: SCHEDULED
[00:56:31.776] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:19:0) after 3m56s
[01:00:27.823] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:19:0)
[01:00:27.842] Build status: SCHEDULED
[01:00:27.842] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:20:0) after 2m16s
[01:02:43.863] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:20:0)
[01:02:43.880] Build status: SCHEDULED
[01:02:43.880] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:21:0) after 9m7s
[01:11:51.004] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:21:0)
[01:11:51.026] Build status: SCHEDULED
[01:11:51.026] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:22:0) after 9m28s
[01:21:19.138] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:22:0)
[01:21:19.158] Build status: SCHEDULED
[01:21:19.158] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:23:0) after 3m6s
[01:24:25.211] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:23:0)
[01:24:25.281] Build status: SCHEDULED
[01:24:25.281] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:24:0) after 6m21s
[01:30:46.304] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:24:0)
[01:30:46.328] Build status: SCHEDULED
[01:30:46.329] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:25:0) after 4m10s
[01:34:56.348] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:25:0)
[01:34:56.430] Build status: SCHEDULED
[01:34:56.430] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:26:0) after 9m3s
[01:43:59.556] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:26:0)
[01:43:59.580] Build status: SCHEDULED
[01:43:59.580] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:27:0) after 7m5s
[01:51:04.716] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:27:0)
[01:51:04.735] Build status: SCHEDULED
[01:51:04.735] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:28:0) after 2m15s
[01:53:19.868] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:28:0)
[01:53:19.887] Build status: SCHEDULED
[01:53:19.887] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:29:0) after 2m52s
[01:56:11.904] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:29:0)
[01:56:11.923] Build status: SCHEDULED
[01:56:11.923] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:30:0) after 9m32s
[02:05:44.000] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:30:0)
[02:05:44.025] Build status: SCHEDULED
[02:05:44.025] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:31:0) after 2m4s
[02:07:48.069] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:31:0)
[02:07:48.086] Build status: SCHEDULED
[02:07:48.086] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:32:0) after 9m25s
[02:17:13.992] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:32:0)
[02:17:14.007] Build status: SCHEDULED
[02:17:14.008] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:33:0) after 4m4s
[02:21:18.243] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:33:0)
[02:21:18.266] Build status: SCHEDULED
[02:21:18.266] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:34:0) after 3m35s
[02:24:53.558] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:34:0)
[02:24:53.574] Build status: SCHEDULED
[02:24:53.574] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:35:0) after 7m7s
[02:32:00.720] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:35:0)
[02:32:00.810] Build status: SCHEDULED
[02:32:00.810] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:36:0) after 1m24s
[02:33:24.948] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:36:0)
[02:33:24.965] Build status: SCHEDULED
[02:33:24.965] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:37:0) after 8m18s
[02:41:42.995] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:37:0)
[02:41:43.070] Build status: SCHEDULED
[02:41:43.070] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:38:0) after 5m35s
[02:47:18.098] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:38:0)
[02:47:18.120] Build status: SCHEDULED
[02:47:18.120] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:39:0) after 2m55s
[02:50:13.254] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:39:0)
[02:50:13.291] Build status: SCHEDULED
[02:50:13.291] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:40:0) after 8m24s
[02:58:37.361] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:40:0)
[02:58:37.378] Build status: SCHEDULED
[02:58:37.378] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:41:0) after 4m5s
[03:02:42.394] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:41:0)
[03:02:42.414] Build status: SCHEDULED
[03:02:42.414] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:42:0) after 8m10s
[03:10:52.497] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:42:0)
[03:10:52.515] Build status: SCHEDULED
[03:10:52.515] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:43:0) after 3m33s
[03:14:25.534] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:43:0)
[03:14:25.547] Build status: SCHEDULED
[03:14:25.547] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:44:0) after 7m3s
[03:21:28.580] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:44:0)
[03:21:28.658] Build status: SCHEDULED
[03:21:28.658] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:45:0) after 8m22s
[03:27:52.434] Received PubSub notification, asking Buildbucket for the build status
[03:27:52.450] Build status: STARTED
[03:29:50.705] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:45:0)
[03:29:50.733] Build status: STARTED
[03:29:50.733] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8886535528425226304:47:0) after 8m29s
[03:36:29.574] Received PubSub notification, asking Buildbucket for the build status
[03:36:29.593] Build:
{
"id": "8721105464707606897",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "coverage"
},
"createdBy": "project:pigweed",
"createTime": "2025-03-06T23:12:09.846310333Z",
"startTime": "2025-03-07T03:27:52.127875685Z",
"endTime": "2025-03-07T03:35:17.780917429Z",
"updateTime": "2025-03-07T03:35:17.780917429Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "7d1b8cd475374e0046119131957027877a0ca92c",
"ref": "refs/heads/main"
}
}
}
[03:36:29.593] Invocation finished in 4h24m22.676297261s with status SUCCEEDED