[21:46:21.384] New invocation is queued and will start shortly
[21:46:23.538] Starting the invocation (attempt 1)
[21:46:23.567] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/cf93680d3cb6eb136748cf7460013419d9692493"
[21:46:23.567] Popped gitiles commit info from properties and tags
[21:46:23.567] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:46:23.567] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:46:23.568] Buildbucket request:
{
"requestId": "8893516890334768992",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-sapphire"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8893516890334768992",
"job": "pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "def1dea944a9b506f4902599f456f0a345379ec5"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@def1dea944a9b506f4902599f456f0a345379ec5",
"title": "def1dea944a9b506f4902599f456f0a345379ec5",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/def1dea944a9b506f4902599f456f0a345379ec5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "a198d7110afe1cbd789e66e8b8b8d87214065ff6"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@a198d7110afe1cbd789e66e8b8b8d87214065ff6",
"title": "a198d7110afe1cbd789e66e8b8b8d87214065ff6",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/a198d7110afe1cbd789e66e8b8b8d87214065ff6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "0f0b4a87f2441a46a66258bab2aaf7f95347e0e3"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@0f0b4a87f2441a46a66258bab2aaf7f95347e0e3",
"title": "0f0b4a87f2441a46a66258bab2aaf7f95347e0e3",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/0f0b4a87f2441a46a66258bab2aaf7f95347e0e3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "9e9628d8e81fab0b726f3c748222cb44ed9a4fbe"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@9e9628d8e81fab0b726f3c748222cb44ed9a4fbe",
"title": "9e9628d8e81fab0b726f3c748222cb44ed9a4fbe",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/9e9628d8e81fab0b726f3c748222cb44ed9a4fbe"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "cf93680d3cb6eb136748cf7460013419d9692493"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@cf93680d3cb6eb136748cf7460013419d9692493",
"title": "cf93680d3cb6eb136748cf7460013419d9692493",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/cf93680d3cb6eb136748cf7460013419d9692493"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "cf93680d3cb6eb136748cf7460013419d9692493",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8893516890334768992"
},
{
"key": "scheduler_job_id",
"value": "pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[21:46:24.044] Scheduled build:
{
"id": "8728086827328144513",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-sapphire"
},
"createdBy": "project:pigweed",
"createTime": "2024-12-19T21:46:23.621138507Z",
"updateTime": "2024-12-19T21:46:23.621138507Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "cf93680d3cb6eb136748cf7460013419d9692493",
"ref": "refs/heads/main"
}
}
}
[21:46:24.044] Task URL: https://cr-buildbucket.appspot.com/build/8728086827328144513
[21:46:24.044] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:2:0) after 4m57s
[21:46:45.352] Received PubSub notification, asking Buildbucket for the build status
[21:46:45.374] Build status: SCHEDULED
[21:51:21.095] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:2:0)
[21:51:21.117] Build status: SCHEDULED
[21:51:21.117] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:4:0) after 4m58s
[21:56:19.136] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:4:0)
[21:56:19.162] Build status: SCHEDULED
[21:56:19.162] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:5:0) after 1m51s
[21:58:10.182] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:5:0)
[21:58:10.214] Build status: SCHEDULED
[21:58:10.214] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:6:0) after 1m18s
[21:59:28.193] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:6:0)
[21:59:28.215] Build status: SCHEDULED
[21:59:28.215] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:7:0) after 5m15s
[22:04:43.272] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:7:0)
[22:04:43.294] Build status: SCHEDULED
[22:04:43.295] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:8:0) after 6m13s
[22:10:56.335] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:8:0)
[22:10:56.521] Build status: SCHEDULED
[22:10:56.521] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:9:0) after 6m59s
[22:17:55.539] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:9:0)
[22:17:55.556] Build status: SCHEDULED
[22:17:55.556] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:10:0) after 7m4s
[22:24:59.621] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:10:0)
[22:24:59.638] Build status: SCHEDULED
[22:24:59.638] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:11:0) after 6m23s
[22:31:22.654] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:11:0)
[22:31:22.672] Build status: SCHEDULED
[22:31:22.672] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:12:0) after 6m22s
[22:37:44.812] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:12:0)
[22:37:44.941] Build status: SCHEDULED
[22:37:44.941] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:13:0) after 8m18s
[22:46:02.959] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:13:0)
[22:46:02.975] Build status: SCHEDULED
[22:46:02.976] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:14:0) after 6m39s
[22:52:41.999] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:14:0)
[22:52:42.015] Build status: SCHEDULED
[22:52:42.015] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:15:0) after 5m50s
[22:58:32.131] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:15:0)
[22:58:32.150] Build status: SCHEDULED
[22:58:32.150] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:16:0) after 3m14s
[23:01:46.162] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:16:0)
[23:01:46.180] Build status: SCHEDULED
[23:01:46.180] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:17:0) after 5m33s
[23:07:19.292] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:17:0)
[23:07:19.307] Build status: SCHEDULED
[23:07:19.307] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:18:0) after 5m41s
[23:13:00.332] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:18:0)
[23:13:00.356] Build status: SCHEDULED
[23:13:00.356] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:19:0) after 2m46s
[23:15:46.377] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:19:0)
[23:15:46.513] Build status: SCHEDULED
[23:15:46.513] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:20:0) after 1m58s
[23:17:44.501] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:20:0)
[23:17:44.581] Build status: SCHEDULED
[23:17:44.581] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:21:0) after 3m25s
[23:21:09.596] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:21:0)
[23:21:09.614] Build status: SCHEDULED
[23:21:09.614] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:22:0) after 7m27s
[23:28:36.607] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:22:0)
[23:28:36.626] Build status: SCHEDULED
[23:28:36.626] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:23:0) after 4m39s
[23:33:16.368] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:23:0)
[23:33:16.418] Build status: SCHEDULED
[23:33:16.418] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:24:0) after 5m21s
[23:38:37.472] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:24:0)
[23:38:37.491] Build status: SCHEDULED
[23:38:37.491] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:25:0) after 5m28s
[23:44:05.558] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:25:0)
[23:44:05.579] Build status: SCHEDULED
[23:44:05.579] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:26:0) after 3m8s
[23:47:13.614] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:26:0)
[23:47:13.635] Build status: SCHEDULED
[23:47:13.635] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:27:0) after 7m17s
[23:54:30.668] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:27:0)
[23:54:30.696] Build status: SCHEDULED
[23:54:30.696] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:28:0) after 5m1s
[23:59:31.810] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:28:0)
[23:59:31.830] Build status: SCHEDULED
[23:59:31.830] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:29:0) after 4m44s
[00:04:16.034] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:29:0)
[00:04:16.069] Build status: SCHEDULED
[00:04:16.069] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:30:0) after 6m25s
[00:10:41.095] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:30:0)
[00:10:41.116] Build status: SCHEDULED
[00:10:41.116] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:31:0) after 4m21s
[00:15:02.187] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:31:0)
[00:15:02.210] Build status: SCHEDULED
[00:15:02.210] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:32:0) after 8m30s
[00:23:32.283] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:32:0)
[00:23:32.313] Build status: SCHEDULED
[00:23:32.313] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:33:0) after 1m53s
[00:25:25.407] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:33:0)
[00:25:25.438] Build status: SCHEDULED
[00:25:25.438] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:34:0) after 5m8s
[00:28:35.289] Received PubSub notification, asking Buildbucket for the build status
[00:28:35.311] Build status: STARTED
[00:30:33.946] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:34:0)
[00:30:33.970] Build status: STARTED
[00:30:33.970] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:36:0) after 7m6s
[00:37:39.988] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:36:0)
[00:37:40.008] Build status: STARTED
[00:37:40.008] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:37:0) after 3m26s
[00:41:06.033] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:37:0)
[00:41:06.168] Build status: STARTED
[00:41:06.168] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:38:0) after 2m36s
[00:43:42.198] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:38:0)
[00:43:42.215] Build status: STARTED
[00:43:42.215] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:39:0) after 4m56s
[00:48:38.253] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:39:0)
[00:48:38.273] Build status: STARTED
[00:48:38.273] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8893516890334768992:40:0) after 7m43s
[00:51:38.547] Received PubSub notification, asking Buildbucket for the build status
[00:51:38.571] Build:
{
"id": "8728086827328144513",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-sapphire"
},
"createdBy": "project:pigweed",
"createTime": "2024-12-19T21:46:23.621138507Z",
"startTime": "2024-12-20T00:28:34.974087679Z",
"endTime": "2024-12-20T00:51:38.121840148Z",
"updateTime": "2024-12-20T00:51:38.121840148Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "cf93680d3cb6eb136748cf7460013419d9692493",
"ref": "refs/heads/main"
}
}
}
[00:51:38.571] Invocation finished in 3h5m17.200929026s with status SUCCEEDED