[23:49:54.417] New invocation is queued and will start shortly
[23:49:56.662] Starting the invocation (attempt 1)
[23:49:56.685] Ignoring gitiles_ref tag without the buildset tag
[23:49:56.685] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:49:56.685] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:49:56.685] Buildbucket request:
{
"requestId": "8834530492071445312",
"builder": {
"project": "pigweed",
"bucket": "experimental.dev.try",
"builder": "experimental-deps"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8834530492071445312",
"job": "pigweed/experimental.dev.try-experimental-deps",
"triggers": [
{
"cron": {
"generation": "57"
},
"id": "cron:v1:57"
}
]
}
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8834530492071445312"
},
{
"key": "scheduler_job_id",
"value": "pigweed/experimental.dev.try-experimental-deps"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[23:49:57.331] Scheduled build:
{
"id": "8669100428901181249",
"builder": {
"project": "pigweed",
"bucket": "experimental.dev.try",
"builder": "experimental-deps"
},
"createdBy": "project:pigweed",
"createTime": "2026-10-01T23:49:56.818194235Z",
"updateTime": "2026-10-01T23:49:56.818194235Z",
"status": "SCHEDULED",
"input": {
}
}
[23:49:57.331] Task URL: https://cr-buildbucket.appspot.com/build/8669100428901181249
[23:49:57.331] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:2:0) after 1m7s
[23:49:58.136] Received PubSub notification, asking Buildbucket for the build status
[23:49:58.174] Build status: SCHEDULED
[23:51:04.463] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:2:0)
[23:51:04.491] Build status: SCHEDULED
[23:51:04.491] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:4:0) after 7m29s
[23:58:33.506] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:4:0)
[23:58:33.527] Build status: SCHEDULED
[23:58:33.527] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:5:0) after 3m0s
[00:01:33.593] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:5:0)
[00:01:33.623] Build status: SCHEDULED
[00:01:33.623] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:6:0) after 3m49s
[00:05:22.877] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:6:0)
[00:05:22.902] Build status: SCHEDULED
[00:05:22.902] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:7:0) after 9m32s
[00:14:54.940] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:7:0)
[00:14:54.963] Build status: SCHEDULED
[00:14:54.963] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:8:0) after 8m48s
[00:23:43.214] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:8:0)
[00:23:43.236] Build status: SCHEDULED
[00:23:43.236] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:9:0) after 6m17s
[00:30:00.247] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:9:0)
[00:30:00.269] Build status: SCHEDULED
[00:30:00.269] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:10:0) after 8m59s
[00:38:59.285] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:10:0)
[00:38:59.372] Build status: SCHEDULED
[00:38:59.372] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:11:0) after 9m48s
[00:48:47.426] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:11:0)
[00:48:47.448] Build status: SCHEDULED
[00:48:47.448] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:12:0) after 3m17s
[00:52:04.552] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:12:0)
[00:52:04.571] Build status: SCHEDULED
[00:52:04.572] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:13:0) after 8m38s
[01:00:42.648] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:13:0)
[01:00:42.674] Build status: SCHEDULED
[01:00:42.674] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:14:0) after 8m33s
[01:09:15.872] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:14:0)
[01:09:15.903] Build status: SCHEDULED
[01:09:15.903] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:15:0) after 5m52s
[01:15:08.001] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:15:0)
[01:15:08.071] Build status: SCHEDULED
[01:15:08.071] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:16:0) after 8m45s
[01:23:53.192] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:16:0)
[01:23:53.212] Build status: SCHEDULED
[01:23:53.213] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:17:0) after 9m16s
[01:33:09.257] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:17:0)
[01:33:09.279] Build status: SCHEDULED
[01:33:09.279] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:18:0) after 1m10s
[01:34:19.388] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:18:0)
[01:34:19.411] Build status: SCHEDULED
[01:34:19.411] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:19:0) after 4m34s
[01:38:53.502] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:19:0)
[01:38:53.528] Build status: SCHEDULED
[01:38:53.528] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:20:0) after 2m50s
[01:41:43.545] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:20:0)
[01:41:43.568] Build status: SCHEDULED
[01:41:43.568] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:21:0) after 2m25s
[01:44:08.707] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:21:0)
[01:44:08.729] Build status: SCHEDULED
[01:44:08.729] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:22:0) after 1m10s
[01:45:18.794] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:22:0)
[01:45:18.813] Build status: SCHEDULED
[01:45:18.813] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:23:0) after 4m47s
[01:50:05.894] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:23:0)
[01:50:05.928] Build status: SCHEDULED
[01:50:05.928] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:24:0) after 8m34s
[01:58:39.979] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:24:0)
[01:58:39.999] Build status: SCHEDULED
[01:58:39.999] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:25:0) after 4m26s
[02:03:06.115] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:25:0)
[02:03:06.140] Build status: SCHEDULED
[02:03:06.140] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:26:0) after 2m11s
[02:05:17.163] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:26:0)
[02:05:17.192] Build status: SCHEDULED
[02:05:17.192] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:27:0) after 2m18s
[02:07:35.206] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:27:0)
[02:07:35.227] Build status: SCHEDULED
[02:07:35.228] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:28:0) after 7m6s
[02:14:41.466] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:28:0)
[02:14:41.491] Build status: SCHEDULED
[02:14:41.492] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:29:0) after 9m58s
[02:24:39.515] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:29:0)
[02:24:39.539] Build status: SCHEDULED
[02:24:39.539] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:30:0) after 8m50s
[02:33:29.764] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:30:0)
[02:33:29.790] Build status: SCHEDULED
[02:33:29.790] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:31:0) after 3m13s
[02:36:42.847] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:31:0)
[02:36:42.865] Build status: SCHEDULED
[02:36:42.865] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:32:0) after 3m51s
[02:40:33.918] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:32:0)
[02:40:33.937] Build status: SCHEDULED
[02:40:33.937] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:33:0) after 3m46s
[02:44:20.004] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:33:0)
[02:44:20.023] Build status: SCHEDULED
[02:44:20.023] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:34:0) after 2m31s
[02:46:51.038] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:34:0)
[02:46:51.059] Build status: SCHEDULED
[02:46:51.059] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:35:0) after 6m21s
[02:53:12.123] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:35:0)
[02:53:12.146] Build status: SCHEDULED
[02:53:12.146] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:36:0) after 2m16s
[02:55:28.162] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:36:0)
[02:55:28.188] Build status: SCHEDULED
[02:55:28.188] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:37:0) after 6m26s
[03:01:54.206] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:37:0)
[03:01:54.227] Build status: SCHEDULED
[03:01:54.227] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:38:0) after 1m57s
[03:03:51.441] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:38:0)
[03:03:51.467] Build status: SCHEDULED
[03:03:51.467] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:39:0) after 3m28s
[03:07:19.540] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:39:0)
[03:07:19.559] Build status: SCHEDULED
[03:07:19.559] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:40:0) after 8m56s
[03:16:15.629] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:40:0)
[03:16:15.650] Build status: SCHEDULED
[03:16:15.650] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:41:0) after 1m56s
[03:18:11.712] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:41:0)
[03:18:11.732] Build status: SCHEDULED
[03:18:11.732] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:42:0) after 2m35s
[03:20:46.800] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:42:0)
[03:20:46.829] Build status: SCHEDULED
[03:20:46.829] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:43:0) after 8m3s
[03:28:49.882] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:43:0)
[03:28:49.905] Build status: SCHEDULED
[03:28:49.905] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:44:0) after 6m16s
[03:35:05.976] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:44:0)
[03:35:05.993] Build status: SCHEDULED
[03:35:05.993] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:45:0) after 7m14s
[03:42:20.109] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:45:0)
[03:42:20.252] Build status: SCHEDULED
[03:42:20.252] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:46:0) after 9m4s
[03:51:24.341] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:46:0)
[03:51:24.374] Build status: SCHEDULED
[03:51:24.375] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:47:0) after 7m25s
[03:58:49.394] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:47:0)
[03:58:49.422] Build status: SCHEDULED
[03:58:49.423] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:48:0) after 7m6s
[04:05:55.575] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:48:0)
[04:05:55.597] Build status: SCHEDULED
[04:05:55.597] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:49:0) after 8m27s
[04:14:22.586] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:49:0)
[04:14:22.606] Build status: SCHEDULED
[04:14:22.606] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:50:0) after 2m31s
[04:16:53.575] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:50:0)
[04:16:53.595] Build status: SCHEDULED
[04:16:53.595] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:51:0) after 6m31s
[04:23:24.740] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:51:0)
[04:23:24.777] Build status: SCHEDULED
[04:23:24.777] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:52:0) after 7m14s
[04:30:38.754] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:52:0)
[04:30:38.771] Build status: SCHEDULED
[04:30:38.771] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:53:0) after 5m45s
[04:36:23.837] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:53:0)
[04:36:23.861] Build status: SCHEDULED
[04:36:23.861] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:54:0) after 5m54s
[04:42:17.952] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:54:0)
[04:42:17.971] Build status: SCHEDULED
[04:42:17.971] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:55:0) after 5m10s
[04:47:27.987] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:55:0)
[04:47:28.005] Build status: SCHEDULED
[04:47:28.005] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:56:0) after 4m10s
[04:51:38.025] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:56:0)
[04:51:38.045] Build status: SCHEDULED
[04:51:38.045] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.try-experimental-deps:8834530492071445312:57:0) after 6m42s
[04:52:43.635] Received PubSub notification, asking Buildbucket for the build status
[04:52:43.669] Build status: STARTED
[04:54:59.443] Received PubSub notification, asking Buildbucket for the build status
[04:54:59.466] Build:
{
"id": "8669100428901181249",
"builder": {
"project": "pigweed",
"bucket": "experimental.dev.try",
"builder": "experimental-deps"
},
"createdBy": "project:pigweed",
"createTime": "2026-10-01T23:49:56.818194235Z",
"startTime": "2026-10-02T04:52:43.423601100Z",
"endTime": "2026-10-02T04:54:58.953273739Z",
"updateTime": "2026-10-02T04:54:58.953273739Z",
"status": "SUCCESS",
"input": {
}
}
[04:54:59.466] Invocation finished in 5h5m5.054674147s with status SUCCEEDED