[01:19:19.828] New invocation is queued and will start shortly
[01:19:21.125] Starting the invocation (attempt 1)
[01:19:21.156] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/experimental/+/d900cc66878216a07212976004500af7aa2ed991"
[01:19:21.156] Popped gitiles commit info from properties and tags
[01:19:21.156] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:19:21.156] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:19:21.156] Buildbucket request:
{
"requestId": "8838420535584326256",
"builder": {
"project": "pigweed",
"bucket": "experimental.dev.ci",
"builder": "experimental-full"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8838420535584326256",
"job": "pigweed/experimental.dev.ci-experimental-full",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/experimental",
"revision": "d900cc66878216a07212976004500af7aa2ed991"
},
"id": "https://pigweed.googlesource.com/pigweed/experimental/+/refs/heads/main@d900cc66878216a07212976004500af7aa2ed991",
"title": "d900cc66878216a07212976004500af7aa2ed991",
"url": "https://pigweed.googlesource.com/pigweed/experimental/+/d900cc66878216a07212976004500af7aa2ed991"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/experimental",
"id": "d900cc66878216a07212976004500af7aa2ed991",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8838420535584326256"
},
{
"key": "scheduler_job_id",
"value": "pigweed/experimental.dev.ci-experimental-full"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[01:19:21.554] Scheduled build:
{
"id": "8672990473463772177",
"builder": {
"project": "pigweed",
"bucket": "experimental.dev.ci",
"builder": "experimental-full"
},
"createdBy": "project:pigweed",
"createTime": "2026-08-20T01:19:21.229639460Z",
"updateTime": "2026-08-20T01:19:21.229639460Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/experimental",
"id": "d900cc66878216a07212976004500af7aa2ed991",
"ref": "refs/heads/main"
}
}
}
[01:19:21.554] Task URL: https://cr-buildbucket.appspot.com/build/8672990473463772177
[01:19:21.554] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:2:0) after 1m45s
[01:19:22.147] Received PubSub notification, asking Buildbucket for the build status
[01:19:22.175] Build status: SCHEDULED
[01:21:06.579] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:2:0)
[01:21:06.621] Build status: SCHEDULED
[01:21:06.621] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:4:0) after 8m46s
[01:29:52.729] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:4:0)
[01:29:52.769] Build status: SCHEDULED
[01:29:52.769] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:5:0) after 9m32s
[01:39:24.784] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:5:0)
[01:39:24.853] Build status: SCHEDULED
[01:39:24.853] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:6:0) after 1m24s
[01:40:48.958] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:6:0)
[01:40:48.980] Build status: SCHEDULED
[01:40:48.980] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:7:0) after 5m46s
[01:46:34.997] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:7:0)
[01:46:35.019] Build status: SCHEDULED
[01:46:35.019] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:8:0) after 5m23s
[01:51:58.035] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:8:0)
[01:51:58.057] Build status: SCHEDULED
[01:51:58.057] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:9:0) after 4m21s
[01:56:19.213] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:9:0)
[01:56:19.284] Build status: SCHEDULED
[01:56:19.284] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:10:0) after 2m14s
[01:58:33.350] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:10:0)
[01:58:33.386] Build status: SCHEDULED
[01:58:33.386] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:11:0) after 6m10s
[02:04:43.538] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:11:0)
[02:04:43.593] Build status: SCHEDULED
[02:04:43.593] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:12:0) after 6m38s
[02:11:21.647] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:12:0)
[02:11:21.665] Build status: SCHEDULED
[02:11:21.665] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:13:0) after 3m25s
[02:14:46.684] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:13:0)
[02:14:46.767] Build status: SCHEDULED
[02:14:46.767] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:14:0) after 4m39s
[02:19:25.784] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:14:0)
[02:19:25.808] Build status: SCHEDULED
[02:19:25.808] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:15:0) after 5m59s
[02:25:24.926] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:15:0)
[02:25:24.945] Build status: SCHEDULED
[02:25:24.945] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:16:0) after 1m32s
[02:26:56.961] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:16:0)
[02:26:57.006] Build status: SCHEDULED
[02:26:57.006] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:17:0) after 8m13s
[02:35:10.030] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:17:0)
[02:35:10.052] Build status: SCHEDULED
[02:35:10.052] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:18:0) after 7m39s
[02:42:49.167] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:18:0)
[02:42:49.216] Build status: SCHEDULED
[02:42:49.216] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:19:0) after 7m45s
[02:50:34.245] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:19:0)
[02:50:34.267] Build status: SCHEDULED
[02:50:34.267] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:20:0) after 7m28s
[02:58:02.327] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:20:0)
[02:58:02.352] Build status: SCHEDULED
[02:58:02.352] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:21:0) after 3m26s
[03:01:28.366] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:21:0)
[03:01:28.389] Build status: SCHEDULED
[03:01:28.389] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:22:0) after 5m48s
[03:07:16.376] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:22:0)
[03:07:16.396] Build status: SCHEDULED
[03:07:16.396] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:23:0) after 2m23s
[03:09:39.541] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:23:0)
[03:09:39.575] Build status: SCHEDULED
[03:09:39.575] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:24:0) after 7m17s
[03:16:56.592] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:24:0)
[03:16:56.663] Build status: SCHEDULED
[03:16:56.663] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:25:0) after 6m19s
[03:23:15.735] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:25:0)
[03:23:15.759] Build status: SCHEDULED
[03:23:15.760] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:26:0) after 4m30s
[03:27:45.833] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:26:0)
[03:27:45.857] Build status: SCHEDULED
[03:27:45.857] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:27:0) after 5m35s
[03:33:20.970] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:27:0)
[03:33:20.990] Build status: SCHEDULED
[03:33:20.990] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:28:0) after 5m52s
[03:39:13.053] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:28:0)
[03:39:13.081] Build status: SCHEDULED
[03:39:13.082] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:29:0) after 7m40s
[03:46:53.106] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:29:0)
[03:46:53.137] Build status: SCHEDULED
[03:46:53.137] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:30:0) after 1m44s
[03:48:37.206] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:30:0)
[03:48:37.235] Build status: SCHEDULED
[03:48:37.235] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:31:0) after 1m33s
[03:50:10.234] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:31:0)
[03:50:10.259] Build status: SCHEDULED
[03:50:10.260] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:32:0) after 5m12s
[03:55:22.275] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:32:0)
[03:55:22.294] Build status: SCHEDULED
[03:55:22.294] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:33:0) after 6m5s
[04:01:27.326] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:33:0)
[04:01:27.354] Build status: SCHEDULED
[04:01:27.354] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:34:0) after 9m51s
[04:11:18.707] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:34:0)
[04:11:18.737] Build status: SCHEDULED
[04:11:18.737] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:35:0) after 7m51s
[04:19:09.754] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:35:0)
[04:19:09.768] Build status: SCHEDULED
[04:19:09.769] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:36:0) after 9m14s
[04:28:23.797] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:36:0)
[04:28:23.815] Build status: SCHEDULED
[04:28:23.815] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:37:0) after 2m32s
[04:30:55.917] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:37:0)
[04:30:55.983] Build status: SCHEDULED
[04:30:55.983] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:38:0) after 2m21s
[04:33:17.009] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:38:0)
[04:33:17.058] Build status: SCHEDULED
[04:33:17.058] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:39:0) after 5m5s
[04:38:22.079] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:39:0)
[04:38:22.106] Build status: SCHEDULED
[04:38:22.106] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:40:0) after 8m33s
[04:46:55.140] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:40:0)
[04:46:55.179] Build status: SCHEDULED
[04:46:55.179] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:41:0) after 2m54s
[04:49:49.216] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:41:0)
[04:49:49.244] Build status: SCHEDULED
[04:49:49.244] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:42:0) after 8m56s
[04:58:45.229] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:42:0)
[04:58:45.306] Build status: SCHEDULED
[04:58:45.306] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:43:0) after 8m49s
[05:07:34.322] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:43:0)
[05:07:34.341] Build status: SCHEDULED
[05:07:34.341] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:44:0) after 2m7s
[05:09:41.356] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:44:0)
[05:09:41.379] Build status: SCHEDULED
[05:09:41.379] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:45:0) after 9m33s
[05:11:49.682] Received PubSub notification, asking Buildbucket for the build status
[05:11:49.702] Build status: STARTED
[05:19:14.393] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:45:0)
[05:19:14.411] Build status: STARTED
[05:19:14.411] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8838420535584326256:47:0) after 9m22s
[05:23:00.486] Received PubSub notification, asking Buildbucket for the build status
[05:23:00.506] Build:
{
"id": "8672990473463772177",
"builder": {
"project": "pigweed",
"bucket": "experimental.dev.ci",
"builder": "experimental-full"
},
"createdBy": "project:pigweed",
"createTime": "2026-08-20T01:19:21.229639460Z",
"startTime": "2026-08-20T05:11:49.456450795Z",
"endTime": "2026-08-20T05:22:59.973373122Z",
"updateTime": "2026-08-20T05:22:59.973373122Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/experimental",
"id": "d900cc66878216a07212976004500af7aa2ed991",
"ref": "refs/heads/main"
}
}
}
[05:23:00.506] Invocation finished in 4h3m40.682379641s with status SUCCEEDED