[13:39:02.809] New invocation is queued and will start shortly
[13:39:04.746] Starting the invocation (attempt 1)
[13:39:04.779] Popped buildset tag "buildset:commit/gitiles/r8.googlesource.com/r8/+/e0af2a82859cd28c82d02530d7cd6775a5da6b14"
[13:39:04.779] Popped gitiles commit info from properties and tags
[13:39:04.779] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:39:04.779] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:39:04.780] Buildbucket request:
{
"requestId": "8855768614204710528",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-default"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8855768614204710528",
"job": "r8/linux-default",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "6a04fb44cad1ab8bfda2b0f0c60a72a28a4af70c"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@6a04fb44cad1ab8bfda2b0f0c60a72a28a4af70c",
"title": "6a04fb44cad1ab8bfda2b0f0c60a72a28a4af70c",
"url": "https://r8.googlesource.com/r8/+/6a04fb44cad1ab8bfda2b0f0c60a72a28a4af70c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "e0af2a82859cd28c82d02530d7cd6775a5da6b14"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@e0af2a82859cd28c82d02530d7cd6775a5da6b14",
"title": "e0af2a82859cd28c82d02530d7cd6775a5da6b14",
"url": "https://r8.googlesource.com/r8/+/e0af2a82859cd28c82d02530d7cd6775a5da6b14"
}
]
}
},
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "e0af2a82859cd28c82d02530d7cd6775a5da6b14",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8855768614204710528"
},
{
"key": "scheduler_job_id",
"value": "r8/linux-default"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[13:39:05.204] Scheduled build:
{
"id": "8690338551436807441",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-default"
},
"number": 1632,
"createdBy": "project:r8",
"createTime": "2026-02-09T13:39:04.827155899Z",
"updateTime": "2026-02-09T13:39:04.827155899Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "e0af2a82859cd28c82d02530d7cd6775a5da6b14",
"ref": "refs/heads/main"
}
}
}
[13:39:05.204] Task URL: https://cr-buildbucket.appspot.com/build/8690338551436807441
[13:39:05.204] Scheduling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:2:0) after 8m51s
[13:39:06.238] Received PubSub notification, asking Buildbucket for the build status
[13:39:06.287] Build status: SCHEDULED
[13:47:56.372] Handling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:2:0)
[13:47:56.405] Build status: SCHEDULED
[13:47:56.405] Scheduling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:4:0) after 7m45s
[13:55:41.495] Handling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:4:0)
[13:55:41.533] Build status: SCHEDULED
[13:55:41.533] Scheduling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:5:0) after 8m52s
[14:04:33.604] Handling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:5:0)
[14:04:33.624] Build status: SCHEDULED
[14:04:33.624] Scheduling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:6:0) after 6m33s
[14:11:06.765] Handling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:6:0)
[14:11:06.794] Build status: SCHEDULED
[14:11:06.794] Scheduling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:7:0) after 7m2s
[14:18:08.829] Handling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:7:0)
[14:18:08.845] Build status: SCHEDULED
[14:18:08.845] Scheduling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:8:0) after 9m38s
[14:27:46.958] Handling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:8:0)
[14:27:46.991] Build status: SCHEDULED
[14:27:46.991] Scheduling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:9:0) after 8m50s
[14:36:37.061] Handling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:9:0)
[14:36:37.082] Build status: SCHEDULED
[14:36:37.082] Scheduling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:10:0) after 6m13s
[14:42:50.098] Handling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:10:0)
[14:42:50.114] Build status: SCHEDULED
[14:42:50.114] Scheduling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:11:0) after 5m13s
[14:46:24.529] Received PubSub notification, asking Buildbucket for the build status
[14:46:24.551] Build status: STARTED
[14:48:03.129] Handling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:11:0)
[14:48:03.152] Build status: STARTED
[14:48:03.152] Scheduling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:13:0) after 6m25s
[14:54:28.172] Handling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:13:0)
[14:54:28.197] Build status: STARTED
[14:54:28.197] Scheduling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:14:0) after 5m5s
[14:59:33.255] Handling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:14:0)
[14:59:33.269] Build status: STARTED
[14:59:33.269] Scheduling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:15:0) after 4m47s
[15:04:20.304] Handling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:15:0)
[15:04:20.333] Build status: STARTED
[15:04:20.333] Scheduling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:16:0) after 6m49s
[15:11:09.344] Handling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:16:0)
[15:11:09.371] Build status: STARTED
[15:11:09.371] Scheduling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:17:0) after 3m30s
[15:14:39.451] Handling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:17:0)
[15:14:39.699] Build status: STARTED
[15:14:39.699] Scheduling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:18:0) after 2m48s
[15:17:27.715] Handling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:18:0)
[15:17:27.732] Build status: STARTED
[15:17:27.732] Scheduling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:19:0) after 3m44s
[15:21:11.721] Handling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:19:0)
[15:21:11.750] Build status: STARTED
[15:21:11.750] Scheduling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:20:0) after 1m14s
[15:22:25.766] Handling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:20:0)
[15:22:25.785] Build status: STARTED
[15:22:25.785] Scheduling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:21:0) after 8m7s
[15:30:32.867] Handling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:21:0)
[15:30:32.883] Build status: STARTED
[15:30:32.883] Scheduling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:22:0) after 4m23s
[15:34:55.904] Handling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:22:0)
[15:34:55.918] Build status: STARTED
[15:34:55.918] Scheduling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:23:0) after 6m44s
[15:41:40.556] Handling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:23:0)
[15:41:40.600] Build status: STARTED
[15:41:40.600] Scheduling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:24:0) after 8m49s
[15:50:29.618] Handling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:24:0)
[15:50:29.641] Build status: STARTED
[15:50:29.641] Scheduling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:25:0) after 7m42s
[15:58:11.656] Handling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:25:0)
[15:58:11.672] Build status: STARTED
[15:58:11.672] Scheduling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:26:0) after 3m49s
[16:02:00.688] Handling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:26:0)
[16:02:00.722] Build status: STARTED
[16:02:00.722] Scheduling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:27:0) after 7m51s
[16:09:51.741] Handling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:27:0)
[16:09:51.758] Build status: STARTED
[16:09:51.758] Scheduling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:28:0) after 5m7s
[16:14:58.730] Handling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:28:0)
[16:14:58.782] Build status: STARTED
[16:14:58.782] Scheduling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:29:0) after 1m3s
[16:16:01.779] Handling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:29:0)
[16:16:01.795] Build status: STARTED
[16:16:01.795] Scheduling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:30:0) after 7m20s
[16:23:21.810] Handling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:30:0)
[16:23:21.852] Build status: STARTED
[16:23:21.852] Scheduling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:31:0) after 7m44s
[16:31:05.936] Handling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:31:0)
[16:31:05.954] Build status: STARTED
[16:31:05.954] Scheduling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:32:0) after 7m25s
[16:38:30.980] Handling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:32:0)
[16:38:31.001] Build status: STARTED
[16:38:31.002] Scheduling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:33:0) after 6m36s
[16:45:06.984] Handling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:33:0)
[16:45:07.031] Build status: STARTED
[16:45:07.031] Scheduling timer "check-buildbucket-build-status" (r8/linux-default:8855768614204710528:34:0) after 5m25s
[16:46:58.793] Received PubSub notification, asking Buildbucket for the build status
[16:46:58.813] Build:
{
"id": "8690338551436807441",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-default"
},
"number": 1632,
"createdBy": "project:r8",
"createTime": "2026-02-09T13:39:04.827155899Z",
"startTime": "2026-02-09T14:46:03.465639442Z",
"endTime": "2026-02-09T16:46:58.367799951Z",
"updateTime": "2026-02-09T16:46:58.367799951Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "e0af2a82859cd28c82d02530d7cd6775a5da6b14",
"ref": "refs/heads/main"
}
}
}
[16:46:58.813] Invocation finished in 3h7m56.009431076s with status SUCCEEDED