[20:13:38.249] New invocation is queued and will start shortly
[20:14:37.853] Starting the invocation (attempt 1)
[20:14:37.875] Ignoring gitiles_ref tag without the buildset tag
[20:14:37.875] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:14:37.875] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:14:37.875] Buildbucket request:
{
"requestId": "8866253036815782688",
"builder": {
"project": "pigweed",
"bucket": "infra.dev.ci",
"builder": "bisector"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8866253036815782688",
"job": "pigweed/infra.dev.ci-bisector",
"triggers": [
{
"cron": {
"generation": "1167"
},
"id": "cron:v1:1167"
}
]
}
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8866253036815782688"
},
{
"key": "scheduler_job_id",
"value": "pigweed/infra.dev.ci-bisector"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[20:14:38.270] Scheduled build:
{
"id": "8700822913581374881",
"builder": {
"project": "pigweed",
"bucket": "infra.dev.ci",
"builder": "bisector"
},
"createdBy": "project:pigweed",
"createTime": "2025-10-16T20:14:37.926109593Z",
"updateTime": "2025-10-16T20:14:37.926109593Z",
"status": "SCHEDULED",
"input": {
}
}
[20:14:38.270] Task URL: https://cr-buildbucket.appspot.com/build/8700822913581374881
[20:14:38.270] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:2:0) after 9m9s
[20:14:39.186] Received PubSub notification, asking Buildbucket for the build status
[20:14:39.203] Build status: SCHEDULED
[20:23:47.291] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:2:0)
[20:23:47.324] Build status: SCHEDULED
[20:23:47.324] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:4:0) after 9m43s
[20:33:30.338] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:4:0)
[20:33:30.407] Build status: SCHEDULED
[20:33:30.407] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:5:0) after 2m45s
[20:36:15.417] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:5:0)
[20:36:15.436] Build status: SCHEDULED
[20:36:15.436] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:6:0) after 6m48s
[20:43:03.450] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:6:0)
[20:43:03.477] Build status: SCHEDULED
[20:43:03.477] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:7:0) after 8m32s
[20:51:35.449] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:7:0)
[20:51:35.465] Build status: SCHEDULED
[20:51:35.465] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:8:0) after 6m29s
[20:58:04.476] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:8:0)
[20:58:04.514] Build status: SCHEDULED
[20:58:04.514] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:9:0) after 3m37s
[21:01:41.492] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:9:0)
[21:01:41.507] Build status: SCHEDULED
[21:01:41.507] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:10:0) after 6m56s
[21:08:37.521] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:10:0)
[21:08:37.537] Build status: SCHEDULED
[21:08:37.537] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:11:0) after 8m46s
[21:17:23.606] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:11:0)
[21:17:23.629] Build status: SCHEDULED
[21:17:23.629] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:12:0) after 6m4s
[21:23:27.643] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:12:0)
[21:23:27.660] Build status: SCHEDULED
[21:23:27.660] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:13:0) after 4m37s
[21:28:04.672] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:13:0)
[21:28:04.694] Build status: SCHEDULED
[21:28:04.695] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:14:0) after 4m58s
[21:33:02.708] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:14:0)
[21:33:02.726] Build status: SCHEDULED
[21:33:02.726] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:15:0) after 7m31s
[21:40:33.767] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:15:0)
[21:40:33.829] Build status: SCHEDULED
[21:40:33.829] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:16:0) after 2m36s
[21:43:09.841] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:16:0)
[21:43:09.858] Build status: SCHEDULED
[21:43:09.858] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:17:0) after 2m10s
[21:45:19.888] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:17:0)
[21:45:19.912] Build status: SCHEDULED
[21:45:19.912] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:18:0) after 6m5s
[21:51:24.926] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:18:0)
[21:51:24.952] Build status: SCHEDULED
[21:51:24.953] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:19:0) after 5m28s
[21:56:52.976] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:19:0)
[21:56:53.002] Build status: SCHEDULED
[21:56:53.002] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:20:0) after 2m32s
[21:59:25.066] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:20:0)
[21:59:25.152] Build status: SCHEDULED
[21:59:25.152] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:21:0) after 9m18s
[22:08:43.168] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:21:0)
[22:08:43.346] Build status: SCHEDULED
[22:08:43.346] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:22:0) after 1m9s
[22:09:52.360] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:22:0)
[22:09:52.381] Build status: SCHEDULED
[22:09:52.381] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:23:0) after 6m8s
[22:16:00.439] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:23:0)
[22:16:00.463] Build status: SCHEDULED
[22:16:00.463] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:24:0) after 8m38s
[22:24:38.476] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:24:0)
[22:24:38.493] Build status: SCHEDULED
[22:24:38.493] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:25:0) after 4m42s
[22:29:20.509] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:25:0)
[22:29:20.539] Build status: SCHEDULED
[22:29:20.539] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:26:0) after 2m34s
[22:31:54.551] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:26:0)
[22:31:54.567] Build status: SCHEDULED
[22:31:54.567] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:27:0) after 7m19s
[22:39:13.661] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:27:0)
[22:39:13.687] Build status: SCHEDULED
[22:39:13.687] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:28:0) after 6m20s
[22:45:33.751] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:28:0)
[22:45:33.767] Build status: SCHEDULED
[22:45:33.767] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:29:0) after 4m13s
[22:49:46.877] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:29:0)
[22:49:46.893] Build status: SCHEDULED
[22:49:46.893] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:30:0) after 6m35s
[22:56:21.869] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:30:0)
[22:56:21.989] Build status: SCHEDULED
[22:56:21.989] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:31:0) after 2m4s
[22:58:26.078] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:31:0)
[22:58:26.099] Build status: SCHEDULED
[22:58:26.099] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:32:0) after 9m59s
[23:08:25.126] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:32:0)
[23:08:25.183] Build status: SCHEDULED
[23:08:25.183] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:33:0) after 1m23s
[23:09:48.196] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:33:0)
[23:09:48.212] Build status: SCHEDULED
[23:09:48.212] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:34:0) after 7m30s
[23:17:18.214] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:34:0)
[23:17:18.233] Build status: SCHEDULED
[23:17:18.233] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:35:0) after 6m12s
[23:23:30.245] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:35:0)
[23:23:30.260] Build status: SCHEDULED
[23:23:30.261] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:36:0) after 5m36s
[23:29:06.323] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:36:0)
[23:29:06.367] Build status: SCHEDULED
[23:29:06.367] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:37:0) after 7m33s
[23:36:39.382] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:37:0)
[23:36:39.404] Build status: SCHEDULED
[23:36:39.404] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:38:0) after 3m44s
[23:40:23.478] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:38:0)
[23:40:23.497] Build status: SCHEDULED
[23:40:23.497] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:39:0) after 2m10s
[23:42:33.511] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:39:0)
[23:42:33.545] Build status: SCHEDULED
[23:42:33.545] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:40:0) after 6m36s
[23:49:09.642] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:40:0)
[23:49:09.659] Build status: SCHEDULED
[23:49:09.660] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:41:0) after 2m15s
[23:51:24.707] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:41:0)
[23:51:24.729] Build status: SCHEDULED
[23:51:24.729] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:42:0) after 1m40s
[23:53:04.741] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:42:0)
[23:53:04.762] Build status: SCHEDULED
[23:53:04.762] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:43:0) after 4m42s
[23:57:46.781] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:43:0)
[23:57:46.806] Build status: SCHEDULED
[23:57:46.806] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:44:0) after 4m28s
[00:02:14.818] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:44:0)
[00:02:14.835] Build status: SCHEDULED
[00:02:14.835] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:45:0) after 9m10s
[00:11:24.852] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:45:0)
[00:11:24.868] Build status: SCHEDULED
[00:11:24.868] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:46:0) after 7m5s
[00:18:29.882] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:46:0)
[00:18:29.910] Build status: SCHEDULED
[00:18:29.910] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:47:0) after 7m53s
[00:26:22.986] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:47:0)
[00:26:23.009] Build status: SCHEDULED
[00:26:23.009] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:48:0) after 5m39s
[00:27:14.276] Received PubSub notification, asking Buildbucket for the build status
[00:27:14.300] Build status: STARTED
[00:32:02.133] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:48:0)
[00:32:02.208] Build status: STARTED
[00:32:02.208] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8866253036815782688:50:0) after 4m14s
[00:33:31.333] Received PubSub notification, asking Buildbucket for the build status
[00:33:31.367] Build:
{
"id": "8700822913581374881",
"builder": {
"project": "pigweed",
"bucket": "infra.dev.ci",
"builder": "bisector"
},
"createdBy": "project:pigweed",
"createTime": "2025-10-16T20:14:37.926109593Z",
"startTime": "2025-10-17T00:27:13.683789581Z",
"endTime": "2025-10-17T00:33:28.757879163Z",
"updateTime": "2025-10-17T00:33:28.757879163Z",
"status": "SUCCESS",
"input": {
}
}
[00:33:31.367] Invocation finished in 4h19m53.129826716s with status SUCCEEDED