[07:18:27.973] New invocation is queued and will start shortly
[07:18:29.194] Starting the invocation (attempt 1)
[07:18:29.223] Popped buildset tag "buildset:commit/gitiles/r8.googlesource.com/r8/+/9ef8f8448788106ab472e117f2eaeb6c820ab516"
[07:18:29.223] Popped gitiles commit info from properties and tags
[07:18:29.223] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:18:29.223] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:18:29.223] Buildbucket request:
{
"requestId": "8947748479170659824",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "desugared_library-jdk11_head"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8947748479170659824",
"job": "r8/desugared_library-jdk11_head",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "9ef8f8448788106ab472e117f2eaeb6c820ab516"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@9ef8f8448788106ab472e117f2eaeb6c820ab516",
"title": "9ef8f8448788106ab472e117f2eaeb6c820ab516",
"url": "https://r8.googlesource.com/r8/+/9ef8f8448788106ab472e117f2eaeb6c820ab516"
}
]
}
},
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "9ef8f8448788106ab472e117f2eaeb6c820ab516",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8947748479170659824"
},
{
"key": "scheduler_job_id",
"value": "r8/desugared_library-jdk11_head"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[07:18:29.626] Scheduled build:
{
"id": "8782318417138918337",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "desugared_library-jdk11_head"
},
"number": 2041,
"createdBy": "project:r8",
"createTime": "2023-05-01T07:18:29.281996318Z",
"updateTime": "2023-05-01T07:18:29.281996318Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "9ef8f8448788106ab472e117f2eaeb6c820ab516",
"ref": "refs/heads/main"
}
}
}
[07:18:29.626] Task URL: https://cr-buildbucket.appspot.com/build/8782318417138918337
[07:18:29.626] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:2:0) after 7m47s
[07:19:02.010] Received PubSub notification, asking Buildbucket for the build status
[07:19:02.044] Build status: STARTED
[07:26:16.752] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:2:0)
[07:26:16.772] Build status: STARTED
[07:26:16.772] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:4:0) after 8m52s
[07:35:08.764] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:4:0)
[07:35:08.797] Build status: STARTED
[07:35:08.797] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:5:0) after 9m37s
[07:44:45.815] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:5:0)
[07:44:45.835] Build status: STARTED
[07:44:45.836] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:6:0) after 9m36s
[07:54:21.912] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:6:0)
[07:54:21.959] Build status: STARTED
[07:54:21.959] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:7:0) after 2m53s
[07:57:15.172] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:7:0)
[07:57:15.210] Build status: STARTED
[07:57:15.210] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:8:0) after 7m32s
[08:04:47.229] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:8:0)
[08:04:47.248] Build status: STARTED
[08:04:47.248] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:9:0) after 9m9s
[08:13:56.264] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:9:0)
[08:13:56.292] Build status: STARTED
[08:13:56.292] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:10:0) after 9m5s
[08:23:01.450] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:10:0)
[08:23:01.474] Build status: STARTED
[08:23:01.474] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:11:0) after 9m55s
[08:32:56.590] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:11:0)
[08:32:56.662] Build status: STARTED
[08:32:56.662] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:12:0) after 7m15s
[08:40:11.961] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:12:0)
[08:40:12.045] Build status: STARTED
[08:40:12.045] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:13:0) after 7m17s
[08:47:29.061] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:13:0)
[08:47:29.096] Build status: STARTED
[08:47:29.096] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:14:0) after 9m14s
[08:56:43.191] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:14:0)
[08:56:43.211] Build status: STARTED
[08:56:43.211] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:15:0) after 3m31s
[09:00:14.418] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:15:0)
[09:00:14.456] Build status: STARTED
[09:00:14.456] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:16:0) after 4m42s
[09:04:56.530] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:16:0)
[09:04:56.551] Build status: STARTED
[09:04:56.551] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:17:0) after 6m17s
[09:11:13.610] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:17:0)
[09:11:13.639] Build status: STARTED
[09:11:13.639] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:18:0) after 2m51s
[09:14:04.657] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:18:0)
[09:14:04.681] Build status: STARTED
[09:14:04.681] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:19:0) after 5m15s
[09:19:19.700] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:19:0)
[09:19:19.720] Build status: STARTED
[09:19:19.720] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:20:0) after 7m52s
[09:27:11.889] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:20:0)
[09:27:11.980] Build status: STARTED
[09:27:11.980] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:21:0) after 5m48s
[09:33:00.004] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:21:0)
[09:33:00.021] Build status: STARTED
[09:33:00.021] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:22:0) after 6m9s
[09:39:09.037] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:22:0)
[09:39:09.056] Build status: STARTED
[09:39:09.056] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:23:0) after 2m47s
[09:41:56.078] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:23:0)
[09:41:56.105] Build status: STARTED
[09:41:56.105] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:24:0) after 7m45s
[09:49:41.132] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:24:0)
[09:49:41.205] Build status: STARTED
[09:49:41.205] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:25:0) after 9m46s
[09:59:27.223] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:25:0)
[09:59:27.244] Build status: STARTED
[09:59:27.244] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:26:0) after 9m14s
[10:08:41.261] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:26:0)
[10:08:41.284] Build status: STARTED
[10:08:41.284] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:27:0) after 9m48s
[10:18:29.406] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:27:0)
[10:18:29.428] Build status: STARTED
[10:18:29.428] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:28:0) after 8m45s
[10:27:14.445] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:28:0)
[10:27:14.466] Build status: STARTED
[10:27:14.466] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:29:0) after 2m27s
[10:29:41.491] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:29:0)
[10:29:41.508] Build status: STARTED
[10:29:41.508] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:30:0) after 9m20s
[10:39:01.640] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:30:0)
[10:39:01.682] Build status: STARTED
[10:39:01.682] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:31:0) after 8m45s
[10:47:46.785] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:31:0)
[10:47:46.813] Build status: STARTED
[10:47:46.813] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:32:0) after 6m50s
[10:54:36.882] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:32:0)
[10:54:36.904] Build status: STARTED
[10:54:36.904] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:33:0) after 2m36s
[10:57:12.923] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:33:0)
[10:57:12.976] Build status: STARTED
[10:57:12.976] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:34:0) after 4m6s
[11:01:18.995] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:34:0)
[11:01:19.071] Build status: STARTED
[11:01:19.071] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:35:0) after 2m21s
[11:03:40.076] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:35:0)
[11:03:40.097] Build status: STARTED
[11:03:40.097] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:36:0) after 9m27s
[11:13:07.164] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:36:0)
[11:13:07.308] Build status: STARTED
[11:13:07.308] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:37:0) after 5m26s
[11:18:33.477] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:37:0)
[11:18:33.500] Build status: STARTED
[11:18:33.500] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:38:0) after 5m28s
[11:24:01.528] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:38:0)
[11:24:01.547] Build status: STARTED
[11:24:01.547] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:39:0) after 4m44s
[11:28:45.739] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:39:0)
[11:28:45.757] Build status: STARTED
[11:28:45.757] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:40:0) after 9m20s
[11:38:05.775] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:40:0)
[11:38:05.803] Build status: STARTED
[11:38:05.803] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:41:0) after 5m44s
[11:43:49.893] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:41:0)
[11:43:49.912] Build status: STARTED
[11:43:49.912] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:42:0) after 6m19s
[11:50:08.928] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:42:0)
[11:50:08.948] Build status: STARTED
[11:50:08.948] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:43:0) after 3m46s
[11:53:55.021] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:43:0)
[11:53:55.041] Build status: STARTED
[11:53:55.041] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:44:0) after 1m18s
[11:55:13.064] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:44:0)
[11:55:13.085] Build status: STARTED
[11:55:13.085] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:45:0) after 8m31s
[12:03:44.111] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:45:0)
[12:03:44.139] Build status: STARTED
[12:03:44.139] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:46:0) after 2m47s
[12:06:31.161] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:46:0)
[12:06:31.182] Build status: STARTED
[12:06:31.182] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:47:0) after 1m6s
[12:07:37.255] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:47:0)
[12:07:37.286] Build status: STARTED
[12:07:37.286] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:48:0) after 7m59s
[12:15:36.303] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:48:0)
[12:15:36.323] Build status: STARTED
[12:15:36.323] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:49:0) after 6m33s
[12:22:09.341] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:49:0)
[12:22:09.386] Build status: STARTED
[12:22:09.386] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:50:0) after 8m14s
[12:30:23.432] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:50:0)
[12:30:23.465] Build status: STARTED
[12:30:23.465] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:51:0) after 9m20s
[12:39:43.596] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:51:0)
[12:39:43.620] Build status: STARTED
[12:39:43.620] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:52:0) after 1m13s
[12:40:56.636] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:52:0)
[12:40:56.654] Build status: STARTED
[12:40:56.654] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:53:0) after 1m30s
[12:42:26.807] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:53:0)
[12:42:26.828] Build status: STARTED
[12:42:26.828] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:54:0) after 6m9s
[12:48:35.982] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:54:0)
[12:48:36.003] Build status: STARTED
[12:48:36.003] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:55:0) after 4m26s
[12:53:01.998] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:55:0)
[12:53:02.015] Build status: STARTED
[12:53:02.015] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:56:0) after 4m0s
[12:57:02.139] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:56:0)
[12:57:02.158] Build status: STARTED
[12:57:02.158] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:57:0) after 5m39s
[13:02:41.188] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:57:0)
[13:02:41.222] Build status: STARTED
[13:02:41.222] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:58:0) after 9m26s
[13:12:07.383] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:58:0)
[13:12:07.404] Build status: STARTED
[13:12:07.404] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:59:0) after 5m10s
[13:17:17.516] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:59:0)
[13:17:17.552] Build status: STARTED
[13:17:17.552] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:60:0) after 5m50s
[13:23:07.570] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:60:0)
[13:23:07.601] Build status: STARTED
[13:23:07.601] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:61:0) after 7m43s
[13:30:50.773] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:61:0)
[13:30:50.795] Build status: STARTED
[13:30:50.795] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:62:0) after 7m52s
[13:38:42.813] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:62:0)
[13:38:42.841] Build status: STARTED
[13:38:42.842] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:63:0) after 3m25s
[13:42:07.998] Handling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:63:0)
[13:42:08.030] Build status: STARTED
[13:42:08.030] Scheduling timer "check-buildbucket-build-status" (r8/desugared_library-jdk11_head:8947748479170659824:64:0) after 4m23s
[13:44:30.829] Received PubSub notification, asking Buildbucket for the build status
[13:44:30.870] Build:
{
"id": "8782318417138918337",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "desugared_library-jdk11_head"
},
"number": 2041,
"createdBy": "project:r8",
"createTime": "2023-05-01T07:18:29.281996318Z",
"startTime": "2023-05-01T07:19:01.465947Z",
"endTime": "2023-05-01T13:44:30.526425074Z",
"updateTime": "2023-05-01T13:44:30.526425074Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "9ef8f8448788106ab472e117f2eaeb6c820ab516",
"ref": "refs/heads/main"
}
}
}
[13:44:30.870] Invocation finished in 6h26m2.911079691s with status SUCCEEDED