[05:03:48.710] New invocation is queued and will start shortly
[05:03:48.710] Triggered by user:mohrr@google.com
[05:03:50.565] Starting the invocation (attempt 1)
[05:03:50.591] Ignoring gitiles_ref tag without the buildset tag
[05:03:50.591] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:03:50.592] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:03:50.592] Buildbucket request:
{
"requestId": "8876638332261850064",
"builder": {
"project": "pigweed",
"bucket": "infra.dev.ci",
"builder": "bisector"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8876638332261850064",
"job": "pigweed/infra.dev.ci-bisector",
"triggers": [
{
"id": "c7eb9818eed08c20",
"title": "Triggered via web UI",
"webui": {
}
}
]
}
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8876638332261850064"
},
{
"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"
}
}
[05:03:51.078] Scheduled build:
{
"id": "8711208269569445185",
"builder": {
"project": "pigweed",
"bucket": "infra.dev.ci",
"builder": "bisector"
},
"createdBy": "project:pigweed",
"createTime": "2025-06-24T05:03:50.653907862Z",
"updateTime": "2025-06-24T05:03:50.653907862Z",
"status": "SCHEDULED",
"input": {
}
}
[05:03:51.078] Task URL: https://cr-buildbucket.appspot.com/build/8711208269569445185
[05:03:51.078] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:2:0) after 9m53s
[05:03:51.518] Received PubSub notification, asking Buildbucket for the build status
[05:03:51.544] Build status: SCHEDULED
[05:13:44.130] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:2:0)
[05:13:44.174] Build status: SCHEDULED
[05:13:44.174] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:4:0) after 9m30s
[05:23:14.207] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:4:0)
[05:23:14.262] Build status: SCHEDULED
[05:23:14.262] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:5:0) after 2m8s
[05:25:22.281] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:5:0)
[05:25:22.308] Build status: SCHEDULED
[05:25:22.308] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:6:0) after 2m28s
[05:27:50.327] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:6:0)
[05:27:50.460] Build status: SCHEDULED
[05:27:50.460] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:7:0) after 5m4s
[05:32:54.478] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:7:0)
[05:32:54.522] Build status: SCHEDULED
[05:32:54.522] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:8:0) after 5m13s
[05:38:07.592] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:8:0)
[05:38:07.626] Build status: SCHEDULED
[05:38:07.626] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:9:0) after 5m2s
[05:43:09.895] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:9:0)
[05:43:09.940] Build status: SCHEDULED
[05:43:09.940] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:10:0) after 1m0s
[05:44:10.519] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:10:0)
[05:44:10.607] Build status: SCHEDULED
[05:44:10.607] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:11:0) after 9m16s
[05:53:26.619] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:11:0)
[05:53:26.646] Build status: SCHEDULED
[05:53:26.646] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:12:0) after 2m19s
[05:55:45.666] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:12:0)
[05:55:45.695] Build status: SCHEDULED
[05:55:45.695] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:13:0) after 1m8s
[05:56:54.661] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:13:0)
[05:56:54.733] Build status: SCHEDULED
[05:56:54.733] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:14:0) after 3m0s
[05:59:54.757] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:14:0)
[05:59:54.800] Build status: SCHEDULED
[05:59:54.800] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:15:0) after 5m18s
[06:05:12.922] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:15:0)
[06:05:12.953] Build status: SCHEDULED
[06:05:12.953] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:16:0) after 2m54s
[06:08:06.971] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:16:0)
[06:08:07.007] Build status: SCHEDULED
[06:08:07.007] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:17:0) after 2m8s
[06:10:15.029] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:17:0)
[06:10:15.065] Build status: SCHEDULED
[06:10:15.065] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:18:0) after 8m18s
[06:18:33.079] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:18:0)
[06:18:33.113] Build status: SCHEDULED
[06:18:33.113] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:19:0) after 7m11s
[06:25:44.128] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:19:0)
[06:25:44.157] Build status: SCHEDULED
[06:25:44.157] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:20:0) after 8m6s
[06:33:50.169] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:20:0)
[06:33:50.253] Build status: SCHEDULED
[06:33:50.253] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:21:0) after 9m56s
[06:43:46.374] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:21:0)
[06:43:46.399] Build status: SCHEDULED
[06:43:46.399] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:22:0) after 5m6s
[06:48:52.468] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:22:0)
[06:48:52.514] Build status: SCHEDULED
[06:48:52.514] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:23:0) after 4m51s
[06:53:43.543] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:23:0)
[06:53:43.569] Build status: SCHEDULED
[06:53:43.569] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:24:0) after 4m20s
[06:58:03.592] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:24:0)
[06:58:03.702] Build status: SCHEDULED
[06:58:03.702] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:25:0) after 8m10s
[07:06:13.881] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:25:0)
[07:06:13.965] Build status: SCHEDULED
[07:06:13.965] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:26:0) after 9m17s
[07:15:31.475] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:26:0)
[07:15:31.503] Build status: SCHEDULED
[07:15:31.503] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:27:0) after 4m1s
[07:19:32.514] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:27:0)
[07:19:32.550] Build status: SCHEDULED
[07:19:32.550] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:28:0) after 7m38s
[07:27:10.572] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:28:0)
[07:27:10.617] Build status: SCHEDULED
[07:27:10.617] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:29:0) after 4m12s
[07:31:22.644] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:29:0)
[07:31:22.699] Build status: SCHEDULED
[07:31:22.699] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:30:0) after 7m14s
[07:38:36.713] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:30:0)
[07:38:36.754] Build status: SCHEDULED
[07:38:36.754] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:31:0) after 9m18s
[07:47:54.770] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:31:0)
[07:47:54.805] Build status: SCHEDULED
[07:47:54.805] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:32:0) after 1m7s
[07:49:01.860] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:32:0)
[07:49:01.887] Build status: SCHEDULED
[07:49:01.887] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:33:0) after 8m48s
[07:57:49.962] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:33:0)
[07:57:49.991] Build status: SCHEDULED
[07:57:49.991] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:34:0) after 8m50s
[08:06:40.067] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:34:0)
[08:06:40.097] Build status: SCHEDULED
[08:06:40.097] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:35:0) after 6m34s
[08:13:14.290] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:35:0)
[08:13:14.358] Build status: SCHEDULED
[08:13:14.358] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:36:0) after 1m1s
[08:14:15.577] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:36:0)
[08:14:15.681] Build status: SCHEDULED
[08:14:15.681] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:37:0) after 8m11s
[08:22:26.721] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:37:0)
[08:22:26.755] Build status: SCHEDULED
[08:22:26.755] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:38:0) after 7m41s
[08:30:07.885] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:38:0)
[08:30:07.903] Build status: SCHEDULED
[08:30:07.903] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:39:0) after 7m5s
[08:37:12.943] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:39:0)
[08:37:12.976] Build status: SCHEDULED
[08:37:12.976] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:40:0) after 1m59s
[08:39:12.278] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:40:0)
[08:39:12.317] Build status: SCHEDULED
[08:39:12.317] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:41:0) after 4m13s
[08:43:25.512] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:41:0)
[08:43:25.547] Build status: SCHEDULED
[08:43:25.547] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:42:0) after 1m38s
[08:45:03.618] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:42:0)
[08:45:03.633] Build status: SCHEDULED
[08:45:03.633] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:43:0) after 7m46s
[08:48:00.838] Received PubSub notification, asking Buildbucket for the build status
[08:48:00.877] Build status: STARTED
[08:52:49.699] Handling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:43:0)
[08:52:49.717] Build status: STARTED
[08:52:49.717] Scheduling timer "check-buildbucket-build-status" (pigweed/infra.dev.ci-bisector:8876638332261850064:45:0) after 5m33s
[08:54:07.771] Received PubSub notification, asking Buildbucket for the build status
[08:54:07.799] Build:
{
"id": "8711208269569445185",
"builder": {
"project": "pigweed",
"bucket": "infra.dev.ci",
"builder": "bisector"
},
"createdBy": "project:pigweed",
"createTime": "2025-06-24T05:03:50.653907862Z",
"startTime": "2025-06-24T08:48:00.586232795Z",
"endTime": "2025-06-24T08:54:06.926552149Z",
"updateTime": "2025-06-24T08:54:06.926552149Z",
"status": "SUCCESS",
"input": {
}
}
[08:54:07.799] Invocation finished in 3h50m19.097800229s with status SUCCEEDED