[10:21:59.416] New invocation is queued and will start shortly
[10:22:01.081] Starting the invocation (attempt 1)
[10:22:01.117] Popped buildset tag "buildset:commit/gitiles/r8.googlesource.com/r8/+/ad57e17074f7b2605dc2a5f756ed797c696df009"
[10:22:01.117] Popped gitiles commit info from properties and tags
[10:22:01.117] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:22:01.117] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:22:01.117] Buildbucket request:
{
"requestId": "9010248839654672560",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-jdk11_release"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/3.0",
"repo": "https://r8.googlesource.com/r8",
"revision": "ad57e17074f7b2605dc2a5f756ed797c696df009"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/3.0@ad57e17074f7b2605dc2a5f756ed797c696df009",
"title": "ad57e17074f7b2605dc2a5f756ed797c696df009",
"url": "https://r8.googlesource.com/r8/+/ad57e17074f7b2605dc2a5f756ed797c696df009"
}
]
}
},
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "ad57e17074f7b2605dc2a5f756ed797c696df009",
"ref": "refs/heads/3.0"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9010248839654672560"
},
{
"key": "scheduler_job_id",
"value": "r8/linux-jdk11_release"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[10:22:01.610] Scheduled build:
{
"id": "8844818777140199073",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-jdk11_release"
},
"number": 32,
"createdBy": "project:r8",
"createTime": "2021-06-10T10:22:01.181158967Z",
"updateTime": "2021-06-10T10:22:01.406249821Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "ad57e17074f7b2605dc2a5f756ed797c696df009",
"ref": "refs/heads/3.0"
}
}
}
[10:22:01.610] Task URL: https://cr-buildbucket.appspot.com/build/8844818777140199073
[10:22:01.610] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:2:0) after 4m9s
[10:26:10.657] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:2:0)
[10:26:10.696] Build status: STARTED
[10:26:10.696] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:3:0) after 2m25s
[10:28:35.795] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:3:0)
[10:28:35.825] Build status: STARTED
[10:28:35.825] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:4:0) after 5m13s
[10:33:48.881] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:4:0)
[10:33:48.912] Build status: STARTED
[10:33:48.912] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:5:0) after 1m26s
[10:35:15.039] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:5:0)
[10:35:15.067] Build status: STARTED
[10:35:15.067] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:6:0) after 6m5s
[10:41:20.185] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:6:0)
[10:41:20.230] Build status: STARTED
[10:41:20.230] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:7:0) after 7m16s
[10:48:36.276] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:7:0)
[10:48:36.320] Build status: STARTED
[10:48:36.320] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:8:0) after 6m53s
[10:55:29.592] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:8:0)
[10:55:29.624] Build status: STARTED
[10:55:29.624] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:9:0) after 4m58s
[11:00:27.651] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:9:0)
[11:00:27.707] Build status: STARTED
[11:00:27.707] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:10:0) after 6m37s
[11:07:04.786] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:10:0)
[11:07:04.820] Build status: STARTED
[11:07:04.820] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:11:0) after 6m6s
[11:13:10.803] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:11:0)
[11:13:10.858] Build status: STARTED
[11:13:10.858] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:12:0) after 5m30s
[11:18:40.924] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:12:0)
[11:18:40.972] Build status: STARTED
[11:18:40.972] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:13:0) after 2m21s
[11:21:01.995] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:13:0)
[11:21:02.027] Build status: STARTED
[11:21:02.027] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:14:0) after 2m3s
[11:23:05.067] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:14:0)
[11:23:05.141] Build status: STARTED
[11:23:05.141] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:15:0) after 6m30s
[11:29:35.161] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:15:0)
[11:29:35.187] Build status: STARTED
[11:29:35.187] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:16:0) after 4m11s
[11:33:46.331] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:16:0)
[11:33:46.363] Build status: STARTED
[11:33:46.363] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:17:0) after 4m51s
[11:38:37.462] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:17:0)
[11:38:37.494] Build status: STARTED
[11:38:37.494] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:18:0) after 2m28s
[11:41:05.565] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:18:0)
[11:41:05.595] Build status: STARTED
[11:41:05.595] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:19:0) after 4m43s
[11:45:48.721] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:19:0)
[11:45:48.748] Build status: STARTED
[11:45:48.748] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:20:0) after 1m46s
[11:47:34.969] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:20:0)
[11:47:34.996] Build status: STARTED
[11:47:34.996] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:21:0) after 1m55s
[11:49:30.019] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:21:0)
[11:49:30.063] Build status: STARTED
[11:49:30.063] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:22:0) after 2m6s
[11:51:36.045] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:22:0)
[11:51:36.089] Build status: STARTED
[11:51:36.089] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:23:0) after 3m30s
[11:55:06.209] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:23:0)
[11:55:06.241] Build status: STARTED
[11:55:06.241] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:24:0) after 3m57s
[11:59:03.262] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:24:0)
[11:59:03.299] Build status: STARTED
[11:59:03.299] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:25:0) after 8m30s
[12:07:33.424] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:25:0)
[12:07:33.530] Build status: STARTED
[12:07:33.530] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:26:0) after 2m8s
[12:09:41.540] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:26:0)
[12:09:41.581] Build status: STARTED
[12:09:41.581] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:27:0) after 6m53s
[12:16:34.589] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:27:0)
[12:16:34.621] Build status: STARTED
[12:16:34.621] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:28:0) after 5m46s
[12:22:20.642] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:28:0)
[12:22:20.671] Build status: STARTED
[12:22:20.671] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:29:0) after 4m32s
[12:26:52.739] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:29:0)
[12:26:52.770] Build status: STARTED
[12:26:52.770] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:30:0) after 8m9s
[12:35:01.827] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:30:0)
[12:35:01.858] Build status: STARTED
[12:35:01.858] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:31:0) after 6m33s
[12:41:34.878] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:31:0)
[12:41:34.906] Build status: STARTED
[12:41:34.906] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:32:0) after 8m7s
[12:49:41.930] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:32:0)
[12:49:41.961] Build status: STARTED
[12:49:41.961] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:33:0) after 2m32s
[12:52:14.105] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:33:0)
[12:52:14.138] Build status: STARTED
[12:52:14.138] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:34:0) after 9m15s
[13:01:29.156] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:34:0)
[13:01:29.185] Build status: STARTED
[13:01:29.185] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:35:0) after 3m32s
[13:05:01.203] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:9010248839654672560:35:0)
[13:05:01.235] Build:
{
"id": "8844818777140199073",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-jdk11_release"
},
"number": 32,
"createdBy": "project:r8",
"createTime": "2021-06-10T10:22:01.181158967Z",
"startTime": "2021-06-10T10:22:14.003481Z",
"endTime": "2021-06-10T13:02:47.196553Z",
"updateTime": "2021-06-10T13:02:47.868151Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "ad57e17074f7b2605dc2a5f756ed797c696df009",
"ref": "refs/heads/3.0"
}
}
}
[13:05:01.235] Invocation finished in 2h43m1.834545724s with status SUCCEEDED