[07:47:33.574] New invocation is queued and will start shortly
[07:47:35.010] Starting the invocation (attempt 1)
[07:47:35.035] Popped buildset tag "buildset:commit/gitiles/r8.googlesource.com/r8/+/0ec3d4137f3d731bca00a6822f3945a52cebd12c"
[07:47:35.035] Popped gitiles commit info from properties and tags
[07:47:35.035] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:47:35.036] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:47:35.036] Buildbucket request:
{
"requestId": "8851170282592349648",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-old"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8851170282592349648",
"job": "r8/linux-old",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "e7ab33df20b545e90556d1852e624e8d91c0dc9e"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@e7ab33df20b545e90556d1852e624e8d91c0dc9e",
"title": "e7ab33df20b545e90556d1852e624e8d91c0dc9e",
"url": "https://r8.googlesource.com/r8/+/e7ab33df20b545e90556d1852e624e8d91c0dc9e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "0ec3d4137f3d731bca00a6822f3945a52cebd12c"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@0ec3d4137f3d731bca00a6822f3945a52cebd12c",
"title": "0ec3d4137f3d731bca00a6822f3945a52cebd12c",
"url": "https://r8.googlesource.com/r8/+/0ec3d4137f3d731bca00a6822f3945a52cebd12c"
}
]
}
},
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "0ec3d4137f3d731bca00a6822f3945a52cebd12c",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8851170282592349648"
},
{
"key": "scheduler_job_id",
"value": "r8/linux-old"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[07:47:35.706] Scheduled build:
{
"id": "8685740220316555009",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-old"
},
"number": 1036,
"createdBy": "project:r8",
"createTime": "2026-04-01T07:47:35.115244532Z",
"updateTime": "2026-04-01T07:47:35.115244532Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "0ec3d4137f3d731bca00a6822f3945a52cebd12c",
"ref": "refs/heads/main"
}
}
}
[07:47:35.706] Task URL: https://cr-buildbucket.appspot.com/build/8685740220316555009
[07:47:35.706] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:2:0) after 4m55s
[07:47:36.620] Received PubSub notification, asking Buildbucket for the build status
[07:47:36.663] Build status: SCHEDULED
[07:47:41.703] Received PubSub notification, asking Buildbucket for the build status
[07:47:41.723] Build status: STARTED
[07:52:30.773] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:2:0)
[07:52:30.794] Build status: STARTED
[07:52:30.794] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:5:0) after 4m50s
[07:57:20.809] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:5:0)
[07:57:20.827] Build status: STARTED
[07:57:20.827] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:6:0) after 9m19s
[08:06:39.840] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:6:0)
[08:06:39.860] Build status: STARTED
[08:06:39.860] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:7:0) after 6m11s
[08:12:50.884] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:7:0)
[08:12:50.900] Build status: STARTED
[08:12:50.900] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:8:0) after 1m7s
[08:13:57.917] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:8:0)
[08:13:57.935] Build status: STARTED
[08:13:57.935] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:9:0) after 1m7s
[08:15:04.951] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:9:0)
[08:15:04.971] Build status: STARTED
[08:15:04.971] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:10:0) after 8m9s
[08:23:13.983] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:10:0)
[08:23:14.002] Build status: STARTED
[08:23:14.003] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:11:0) after 2m13s
[08:25:27.043] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:11:0)
[08:25:27.060] Build status: STARTED
[08:25:27.060] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:12:0) after 8m50s
[08:34:17.076] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:12:0)
[08:34:17.099] Build status: STARTED
[08:34:17.108] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:13:0) after 6m7s
[08:40:24.122] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:13:0)
[08:40:24.140] Build status: STARTED
[08:40:24.140] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:14:0) after 5m46s
[08:46:10.166] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:14:0)
[08:46:10.200] Build status: STARTED
[08:46:10.200] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:15:0) after 8m28s
[08:54:38.178] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:15:0)
[08:54:38.200] Build status: STARTED
[08:54:38.200] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:16:0) after 3m20s
[08:57:58.243] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:16:0)
[08:57:58.259] Build status: STARTED
[08:57:58.259] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:17:0) after 8m31s
[09:06:29.252] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:17:0)
[09:06:29.270] Build status: STARTED
[09:06:29.270] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:18:0) after 4m49s
[09:11:18.241] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:18:0)
[09:11:18.259] Build status: STARTED
[09:11:18.259] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:19:0) after 8m23s
[09:19:41.321] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:19:0)
[09:19:41.343] Build status: STARTED
[09:19:41.343] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:20:0) after 4m32s
[09:24:13.361] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:20:0)
[09:24:13.380] Build status: STARTED
[09:24:13.380] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:21:0) after 7m38s
[09:31:51.476] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:21:0)
[09:31:51.572] Build status: STARTED
[09:31:51.572] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:22:0) after 4m50s
[09:36:41.586] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:22:0)
[09:36:41.608] Build status: STARTED
[09:36:41.608] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:23:0) after 7m16s
[09:43:57.670] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:23:0)
[09:43:57.688] Build status: STARTED
[09:43:57.688] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:24:0) after 9m29s
[09:53:26.801] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:24:0)
[09:53:26.815] Build status: STARTED
[09:53:26.815] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:25:0) after 4m41s
[09:58:07.830] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:25:0)
[09:58:07.854] Build status: STARTED
[09:58:07.854] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:26:0) after 5m51s
[10:03:58.869] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:26:0)
[10:03:58.890] Build status: STARTED
[10:03:58.890] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:27:0) after 2m51s
[10:06:49.905] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:27:0)
[10:06:49.923] Build status: STARTED
[10:06:49.923] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:28:0) after 1m44s
[10:08:33.890] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:28:0)
[10:08:33.906] Build status: STARTED
[10:08:33.906] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:29:0) after 1m19s
[10:09:52.981] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:29:0)
[10:09:53.006] Build status: STARTED
[10:09:53.006] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:30:0) after 7m46s
[10:17:39.041] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:30:0)
[10:17:39.061] Build status: STARTED
[10:17:39.061] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:31:0) after 8m46s
[10:26:25.109] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:31:0)
[10:26:25.278] Build status: STARTED
[10:26:25.279] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:32:0) after 1m13s
[10:27:38.277] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:32:0)
[10:27:38.374] Build status: STARTED
[10:27:38.374] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:33:0) after 4m23s
[10:32:01.431] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:33:0)
[10:32:01.454] Build status: STARTED
[10:32:01.454] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:34:0) after 1m27s
[10:33:28.465] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:34:0)
[10:33:28.479] Build status: STARTED
[10:33:28.479] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:35:0) after 5m16s
[10:38:44.526] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:35:0)
[10:38:44.561] Build status: STARTED
[10:38:44.561] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:36:0) after 8m35s
[10:47:19.588] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:36:0)
[10:47:19.603] Build status: STARTED
[10:47:19.603] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:37:0) after 6m5s
[10:53:24.625] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:37:0)
[10:53:24.650] Build status: STARTED
[10:53:24.650] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:38:0) after 5m6s
[10:58:30.664] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:38:0)
[10:58:30.687] Build status: STARTED
[10:58:30.687] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:39:0) after 4m52s
[11:03:22.758] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:39:0)
[11:03:22.776] Build status: STARTED
[11:03:22.776] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:40:0) after 7m14s
[11:10:36.824] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:40:0)
[11:10:36.841] Build status: STARTED
[11:10:36.841] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:41:0) after 9m45s
[11:20:21.854] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:41:0)
[11:20:21.869] Build status: STARTED
[11:20:21.869] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:42:0) after 8m32s
[11:28:53.898] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:42:0)
[11:28:53.914] Build status: STARTED
[11:28:53.914] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:43:0) after 7m11s
[11:36:04.973] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:43:0)
[11:36:05.032] Build status: STARTED
[11:36:05.032] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:44:0) after 2m8s
[11:38:13.051] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:44:0)
[11:38:13.130] Build status: STARTED
[11:38:13.130] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:45:0) after 7m32s
[11:45:45.113] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:45:0)
[11:45:45.138] Build status: STARTED
[11:45:45.138] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:46:0) after 3m21s
[11:49:06.204] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:46:0)
[11:49:06.227] Build status: STARTED
[11:49:06.227] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:47:0) after 5m3s
[11:54:09.244] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:47:0)
[11:54:09.300] Build status: STARTED
[11:54:09.300] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:48:0) after 3m17s
[11:57:26.365] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:48:0)
[11:57:26.380] Build status: STARTED
[11:57:26.381] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:49:0) after 7m44s
[12:05:10.453] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:49:0)
[12:05:10.475] Build status: STARTED
[12:05:10.475] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:50:0) after 9m15s
[12:14:25.492] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:50:0)
[12:14:25.553] Build status: STARTED
[12:14:25.553] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:51:0) after 1m44s
[12:16:09.708] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:51:0)
[12:16:09.733] Build status: STARTED
[12:16:09.733] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:52:0) after 7m10s
[12:23:19.804] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:52:0)
[12:23:19.821] Build status: STARTED
[12:23:19.821] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:53:0) after 9m54s
[12:33:13.814] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:53:0)
[12:33:13.837] Build status: STARTED
[12:33:13.837] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:54:0) after 4m33s
[12:37:46.863] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:54:0)
[12:37:46.885] Build status: STARTED
[12:37:46.885] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:55:0) after 3m1s
[12:40:47.960] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:55:0)
[12:40:48.000] Build status: STARTED
[12:40:48.000] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:56:0) after 3m0s
[12:43:47.994] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:56:0)
[12:43:48.016] Build status: STARTED
[12:43:48.016] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:57:0) after 8m27s
[12:52:15.035] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:57:0)
[12:52:15.059] Build status: STARTED
[12:52:15.059] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:58:0) after 8m10s
[13:00:25.077] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:58:0)
[13:00:25.098] Build status: STARTED
[13:00:25.098] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:59:0) after 2m54s
[13:03:19.114] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:59:0)
[13:03:19.132] Build status: STARTED
[13:03:19.132] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:60:0) after 1m35s
[13:04:54.116] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:60:0)
[13:04:54.137] Build status: STARTED
[13:04:54.137] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:61:0) after 2m48s
[13:07:42.153] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:61:0)
[13:07:42.181] Build status: STARTED
[13:07:42.181] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:62:0) after 9m37s
[13:17:19.199] Handling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:62:0)
[13:17:19.348] Build status: STARTED
[13:17:19.348] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8851170282592349648:63:0) after 3m18s
[13:19:43.007] Received PubSub notification, asking Buildbucket for the build status
[13:19:43.027] Build:
{
"id": "8685740220316555009",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-old"
},
"number": 1036,
"createdBy": "project:r8",
"createTime": "2026-04-01T07:47:35.115244532Z",
"startTime": "2026-04-01T07:47:41.525078014Z",
"endTime": "2026-04-01T13:19:42.776983787Z",
"updateTime": "2026-04-01T13:19:42.776983787Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "0ec3d4137f3d731bca00a6822f3945a52cebd12c",
"ref": "refs/heads/main"
}
}
}
[13:19:43.027] Invocation finished in 5h32m9.465573704s with status SUCCEEDED