[12:13:52.233] New invocation is queued and will start shortly
[12:13:53.301] Starting the invocation (attempt 1)
[12:13:53.338] Popped buildset tag "buildset:commit/gitiles/r8.googlesource.com/r8/+/03f8577d014124c37760c0d6439a8dcbeb0742a1"
[12:13:53.338] Popped gitiles commit info from properties and tags
[12:13:53.338] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:13:53.338] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:13:53.338] Buildbucket request:
{
"requestId": "8999370164785597776",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-jdk11"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "814acb3de849cca3e769fe2548b0dc49126c1dec"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@814acb3de849cca3e769fe2548b0dc49126c1dec",
"title": "814acb3de849cca3e769fe2548b0dc49126c1dec",
"url": "https://r8.googlesource.com/r8/+/814acb3de849cca3e769fe2548b0dc49126c1dec"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "82553220ea29af29b0fcc205a98be2e1636e3835"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@82553220ea29af29b0fcc205a98be2e1636e3835",
"title": "82553220ea29af29b0fcc205a98be2e1636e3835",
"url": "https://r8.googlesource.com/r8/+/82553220ea29af29b0fcc205a98be2e1636e3835"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "10b104ffe3b77d3e289ac828ca8281da5d5c5e90"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@10b104ffe3b77d3e289ac828ca8281da5d5c5e90",
"title": "10b104ffe3b77d3e289ac828ca8281da5d5c5e90",
"url": "https://r8.googlesource.com/r8/+/10b104ffe3b77d3e289ac828ca8281da5d5c5e90"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "78ece7e0645f7777e1a9f0669b119549ecd0a409"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@78ece7e0645f7777e1a9f0669b119549ecd0a409",
"title": "78ece7e0645f7777e1a9f0669b119549ecd0a409",
"url": "https://r8.googlesource.com/r8/+/78ece7e0645f7777e1a9f0669b119549ecd0a409"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "9f13e38b69efdb0e8ec87c629060992106e05dc6"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@9f13e38b69efdb0e8ec87c629060992106e05dc6",
"title": "9f13e38b69efdb0e8ec87c629060992106e05dc6",
"url": "https://r8.googlesource.com/r8/+/9f13e38b69efdb0e8ec87c629060992106e05dc6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "7ff0dffed27efc0f9c2f3caebd22b838d43575be"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@7ff0dffed27efc0f9c2f3caebd22b838d43575be",
"title": "7ff0dffed27efc0f9c2f3caebd22b838d43575be",
"url": "https://r8.googlesource.com/r8/+/7ff0dffed27efc0f9c2f3caebd22b838d43575be"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "03f8577d014124c37760c0d6439a8dcbeb0742a1"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@03f8577d014124c37760c0d6439a8dcbeb0742a1",
"title": "03f8577d014124c37760c0d6439a8dcbeb0742a1",
"url": "https://r8.googlesource.com/r8/+/03f8577d014124c37760c0d6439a8dcbeb0742a1"
}
]
}
},
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "03f8577d014124c37760c0d6439a8dcbeb0742a1",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8999370164785597776"
},
{
"key": "scheduler_job_id",
"value": "r8/linux-jdk11"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[12:13:53.839] Scheduled build:
{
"id": "8833940102892131553",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-jdk11"
},
"number": 608,
"createdBy": "project:r8",
"createTime": "2021-10-08T12:13:53.408128434Z",
"updateTime": "2021-10-08T12:13:53.615608335Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "03f8577d014124c37760c0d6439a8dcbeb0742a1",
"ref": "refs/heads/main"
}
}
}
[12:13:53.839] Task URL: https://cr-buildbucket.appspot.com/build/8833940102892131553
[12:13:53.839] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:2:0) after 4m51s
[12:18:45.312] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:2:0)
[12:18:45.343] Build status: SCHEDULED
[12:18:45.343] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:3:0) after 5m54s
[12:24:39.372] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:3:0)
[12:24:39.403] Build status: SCHEDULED
[12:24:39.403] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:4:0) after 2m38s
[12:27:17.601] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:4:0)
[12:27:17.685] Build status: SCHEDULED
[12:27:17.685] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:5:0) after 7m57s
[12:35:14.704] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:5:0)
[12:35:14.777] Build status: SCHEDULED
[12:35:14.777] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:6:0) after 9m42s
[12:44:56.814] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:6:0)
[12:44:56.844] Build status: SCHEDULED
[12:44:56.844] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:7:0) after 6m15s
[12:51:12.000] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:7:0)
[12:51:12.112] Build status: SCHEDULED
[12:51:12.112] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:8:0) after 8m36s
[12:59:48.133] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:8:0)
[12:59:48.286] Build status: SCHEDULED
[12:59:48.286] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:9:0) after 7m35s
[13:07:23.576] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:9:0)
[13:07:23.651] Build status: SCHEDULED
[13:07:23.651] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:10:0) after 1m24s
[13:08:48.172] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:10:0)
[13:08:48.230] Build status: SCHEDULED
[13:08:48.230] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:11:0) after 3m7s
[13:11:55.252] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:11:0)
[13:11:55.296] Build status: SCHEDULED
[13:11:55.296] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:12:0) after 3m6s
[13:15:01.339] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:12:0)
[13:15:01.371] Build status: SCHEDULED
[13:15:01.371] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:13:0) after 8m9s
[13:23:10.356] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:13:0)
[13:23:10.386] Build status: SCHEDULED
[13:23:10.386] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:14:0) after 3m53s
[13:27:03.506] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:14:0)
[13:27:03.537] Build status: SCHEDULED
[13:27:03.537] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:15:0) after 8m13s
[13:35:16.601] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:15:0)
[13:35:16.633] Build status: SCHEDULED
[13:35:16.633] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:16:0) after 8m1s
[13:37:38.081] Received PubSub notification, asking Buildbucket for the build status
[13:37:38.109] Build status: STARTED
[13:43:17.654] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:16:0)
[13:43:17.698] Build status: STARTED
[13:43:17.698] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:18:0) after 6m54s
[13:50:11.796] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:18:0)
[13:50:11.864] Build status: STARTED
[13:50:11.864] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:19:0) after 6m28s
[13:56:39.895] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:19:0)
[13:56:39.927] Build status: STARTED
[13:56:39.927] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:20:0) after 5m54s
[14:02:33.979] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:20:0)
[14:02:34.014] Build status: STARTED
[14:02:34.014] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:21:0) after 5m41s
[14:08:15.059] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:21:0)
[14:08:15.110] Build status: STARTED
[14:08:15.110] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:22:0) after 8m14s
[14:16:29.337] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:22:0)
[14:16:29.426] Build status: STARTED
[14:16:29.426] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:23:0) after 3m33s
[14:20:02.637] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:23:0)
[14:20:02.685] Build status: STARTED
[14:20:02.685] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:24:0) after 5m27s
[14:25:29.995] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:24:0)
[14:25:30.024] Build status: STARTED
[14:25:30.024] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:25:0) after 7m32s
[14:33:02.048] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:25:0)
[14:33:02.077] Build status: STARTED
[14:33:02.077] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:26:0) after 9m58s
[14:43:00.097] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:26:0)
[14:43:00.136] Build status: STARTED
[14:43:00.136] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:27:0) after 9m49s
[14:52:49.221] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:27:0)
[14:52:49.261] Build status: STARTED
[14:52:49.261] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:28:0) after 4m44s
[14:57:33.297] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:28:0)
[14:57:33.384] Build status: STARTED
[14:57:33.384] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:29:0) after 8m36s
[15:06:09.481] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:29:0)
[15:06:09.557] Build status: STARTED
[15:06:09.557] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:30:0) after 9m22s
[15:15:32.001] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:30:0)
[15:15:32.031] Build status: STARTED
[15:15:32.031] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:31:0) after 7m43s
[15:23:15.049] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:31:0)
[15:23:15.081] Build status: STARTED
[15:23:15.081] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:32:0) after 8m10s
[15:31:25.207] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:32:0)
[15:31:25.240] Build status: STARTED
[15:31:25.240] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:33:0) after 6m50s
[15:38:15.239] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:33:0)
[15:38:15.269] Build status: STARTED
[15:38:15.269] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:34:0) after 4m46s
[15:43:01.299] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:34:0)
[15:43:01.377] Build status: STARTED
[15:43:01.377] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:35:0) after 4m5s
[15:47:06.521] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:35:0)
[15:47:06.551] Build status: STARTED
[15:47:06.551] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:36:0) after 1m22s
[15:48:28.631] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:36:0)
[15:48:28.660] Build status: STARTED
[15:48:28.660] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:37:0) after 1m52s
[15:50:20.681] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:37:0)
[15:50:20.720] Build status: STARTED
[15:50:20.720] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:38:0) after 1m47s
[15:52:07.907] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:38:0)
[15:52:07.934] Build status: STARTED
[15:52:07.934] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11:8999370164785597776:39:0) after 3m35s
[15:54:53.062] Received PubSub notification, asking Buildbucket for the build status
[15:54:53.112] Build:
{
"id": "8833940102892131553",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-jdk11"
},
"number": 608,
"createdBy": "project:r8",
"createTime": "2021-10-08T12:13:53.408128434Z",
"startTime": "2021-10-08T13:37:36.000406Z",
"endTime": "2021-10-08T15:54:52.715077543Z",
"updateTime": "2021-10-08T15:54:52.715494263Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "03f8577d014124c37760c0d6439a8dcbeb0742a1",
"ref": "refs/heads/main"
}
}
}
[15:54:53.112] Invocation finished in 3h41m0.892959158s with status FAILED