[15:12:43.232] New invocation is queued and will start shortly
[15:12:47.942] Starting the invocation (attempt 1)
[15:12:47.986] Popped buildset tag "buildset:commit/gitiles/r8.googlesource.com/r8/+/bacd974e744c12fea874a908a97bc3b62f0b539d"
[15:12:47.986] Popped gitiles commit info from properties and tags
[15:12:47.986] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:12:47.986] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:12:47.986] Buildbucket request:
{
"requestId": "8975531910355892528",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-jdk11_release"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8975531910355892528",
"job": "r8/linux-jdk11_release",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/3.2",
"repo": "https://r8.googlesource.com/r8",
"revision": "bacd974e744c12fea874a908a97bc3b62f0b539d"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/3.2@bacd974e744c12fea874a908a97bc3b62f0b539d",
"title": "bacd974e744c12fea874a908a97bc3b62f0b539d",
"url": "https://r8.googlesource.com/r8/+/bacd974e744c12fea874a908a97bc3b62f0b539d"
}
]
}
},
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "bacd974e744c12fea874a908a97bc3b62f0b539d",
"ref": "refs/heads/3.2"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8975531910355892528"
},
{
"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"
}
}
[15:12:48.527] Scheduled build:
{
"id": "8810101844641436897",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-jdk11_release"
},
"number": 309,
"createdBy": "project:r8",
"createTime": "2022-06-28T15:12:48.050537361Z",
"updateTime": "2022-06-28T15:12:48.050537361Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "bacd974e744c12fea874a908a97bc3b62f0b539d",
"ref": "refs/heads/3.2"
}
}
}
[15:12:48.527] Task URL: https://cr-buildbucket.appspot.com/build/8810101844641436897
[15:12:48.527] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:2:0) after 6m59s
[15:19:47.545] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:2:0)
[15:19:47.575] Build status: SCHEDULED
[15:19:47.575] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:3:0) after 4m26s
[15:24:13.592] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:3:0)
[15:24:13.641] Build status: SCHEDULED
[15:24:13.641] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:4:0) after 2m17s
[15:26:30.714] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:4:0)
[15:26:30.747] Build status: SCHEDULED
[15:26:30.747] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:5:0) after 6m15s
[15:32:45.763] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:5:0)
[15:32:45.799] Build status: SCHEDULED
[15:32:45.799] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:6:0) after 3m58s
[15:36:43.948] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:6:0)
[15:36:44.030] Build status: SCHEDULED
[15:36:44.030] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:7:0) after 9m42s
[15:46:26.150] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:7:0)
[15:46:26.200] Build status: SCHEDULED
[15:46:26.200] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:8:0) after 1m19s
[15:47:45.245] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:8:0)
[15:47:45.279] Build status: SCHEDULED
[15:47:45.279] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:9:0) after 8m57s
[15:56:42.692] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:9:0)
[15:56:42.735] Build status: SCHEDULED
[15:56:42.735] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:10:0) after 7m29s
[16:04:11.759] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:10:0)
[16:04:11.794] Build status: SCHEDULED
[16:04:11.795] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:11:0) after 1m43s
[16:05:54.998] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:11:0)
[16:05:55.035] Build status: SCHEDULED
[16:05:55.035] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:12:0) after 7m4s
[16:12:59.194] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:12:0)
[16:12:59.225] Build status: SCHEDULED
[16:12:59.225] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:13:0) after 8m2s
[16:21:01.334] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:13:0)
[16:21:01.367] Build status: SCHEDULED
[16:21:01.367] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:14:0) after 7m6s
[16:28:07.546] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:14:0)
[16:28:07.643] Build status: SCHEDULED
[16:28:07.643] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:15:0) after 3m11s
[16:31:18.663] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:15:0)
[16:31:18.751] Build status: SCHEDULED
[16:31:18.751] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:16:0) after 8m5s
[16:39:23.774] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:16:0)
[16:39:23.806] Build status: SCHEDULED
[16:39:23.806] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:17:0) after 6m28s
[16:45:51.826] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:17:0)
[16:45:51.848] Build status: SCHEDULED
[16:45:51.848] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:18:0) after 7m39s
[16:53:18.504] Received PubSub notification, asking Buildbucket for the build status
[16:53:18.535] Build status: STARTED
[16:53:31.003] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:18:0)
[16:53:31.037] Build status: STARTED
[16:53:31.037] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:20:0) after 4m13s
[16:57:44.117] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:20:0)
[16:57:44.137] Build status: STARTED
[16:57:44.137] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:21:0) after 5m42s
[17:03:26.172] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:21:0)
[17:03:26.227] Build status: STARTED
[17:03:26.227] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:22:0) after 7m15s
[17:10:41.373] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:22:0)
[17:10:41.459] Build status: STARTED
[17:10:41.459] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:23:0) after 5m55s
[17:16:36.478] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:23:0)
[17:16:36.511] Build status: STARTED
[17:16:36.511] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:24:0) after 9m15s
[17:25:51.685] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:24:0)
[17:25:51.721] Build status: STARTED
[17:25:51.721] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:25:0) after 6m48s
[17:32:39.916] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:25:0)
[17:32:39.950] Build status: STARTED
[17:32:39.950] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:26:0) after 1m45s
[17:34:25.063] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:26:0)
[17:34:25.088] Build status: STARTED
[17:34:25.088] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:27:0) after 3m55s
[17:38:20.106] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:27:0)
[17:38:20.138] Build status: STARTED
[17:38:20.138] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:28:0) after 5m56s
[17:44:16.182] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:28:0)
[17:44:16.218] Build status: STARTED
[17:44:16.218] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:29:0) after 8m40s
[17:52:56.344] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:29:0)
[17:52:56.450] Build status: STARTED
[17:52:56.450] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:30:0) after 1m28s
[17:54:24.478] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:30:0)
[17:54:24.537] Build status: STARTED
[17:54:24.537] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:31:0) after 2m57s
[17:57:21.613] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:31:0)
[17:57:21.649] Build status: STARTED
[17:57:21.650] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:32:0) after 4m16s
[18:01:37.901] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:32:0)
[18:01:37.932] Build status: STARTED
[18:01:37.932] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:33:0) after 3m46s
[18:05:24.105] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:33:0)
[18:05:24.133] Build status: STARTED
[18:05:24.133] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:34:0) after 6m12s
[18:11:36.251] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:34:0)
[18:11:36.290] Build status: STARTED
[18:11:36.290] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:35:0) after 6m57s
[18:18:33.324] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:35:0)
[18:18:33.360] Build status: STARTED
[18:18:33.360] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:36:0) after 2m55s
[18:21:28.378] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:36:0)
[18:21:28.425] Build status: STARTED
[18:21:28.425] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:37:0) after 9m30s
[18:30:58.460] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:37:0)
[18:30:58.598] Build status: STARTED
[18:30:58.598] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:38:0) after 4m20s
[18:35:18.578] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:38:0)
[18:35:18.635] Build status: STARTED
[18:35:18.635] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:39:0) after 8m48s
[18:44:06.700] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:39:0)
[18:44:06.731] Build status: STARTED
[18:44:06.731] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:40:0) after 3m56s
[18:48:02.820] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:40:0)
[18:48:02.856] Build status: STARTED
[18:48:02.856] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:41:0) after 2m18s
[18:50:20.961] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:41:0)
[18:50:21.006] Build status: STARTED
[18:50:21.006] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:42:0) after 8m40s
[18:59:01.234] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:42:0)
[18:59:01.354] Build status: STARTED
[18:59:01.354] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:43:0) after 9m13s
[19:08:14.571] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:43:0)
[19:08:14.608] Build status: STARTED
[19:08:14.608] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:44:0) after 9m52s
[19:18:06.627] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:44:0)
[19:18:06.660] Build status: STARTED
[19:18:06.660] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:45:0) after 7m12s
[19:25:18.957] Handling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:45:0)
[19:25:18.998] Build status: STARTED
[19:25:18.998] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk11_release:8975531910355892528:46:0) after 9m24s
[19:33:21.989] Received PubSub notification, asking Buildbucket for the build status
[19:33:22.037] Build:
{
"id": "8810101844641436897",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-jdk11_release"
},
"number": 309,
"createdBy": "project:r8",
"createTime": "2022-06-28T15:12:48.050537361Z",
"startTime": "2022-06-28T16:53:14.974316Z",
"endTime": "2022-06-28T19:33:21.684055826Z",
"updateTime": "2022-06-28T19:33:21.684055826Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "bacd974e744c12fea874a908a97bc3b62f0b539d",
"ref": "refs/heads/3.2"
}
}
}
[19:33:22.037] Invocation finished in 4h20m38.820715476s with status SUCCEEDED