[17:58:13.260] New invocation is queued and will start shortly
[17:58:14.540] Starting the invocation (attempt 1)
[17:58:14.607] Popped buildset tag "buildset:commit/gitiles/r8.googlesource.com/r8/+/744d742137a82656b8ee27513f975e0528aecd78"
[17:58:14.607] Popped gitiles commit info from properties and tags
[17:58:14.607] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:58:14.607] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:58:14.607] Buildbucket request:
{
"requestId": "9013662820823554288",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-jdk8"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "744d742137a82656b8ee27513f975e0528aecd78"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@744d742137a82656b8ee27513f975e0528aecd78",
"title": "744d742137a82656b8ee27513f975e0528aecd78",
"url": "https://r8.googlesource.com/r8/+/744d742137a82656b8ee27513f975e0528aecd78"
}
]
}
},
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "744d742137a82656b8ee27513f975e0528aecd78",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9013662820823554288"
},
{
"key": "scheduler_job_id",
"value": "r8/linux-jdk8"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[17:58:15.905] Scheduled build:
{
"id": "8848232758600762320",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-jdk8"
},
"number": 809,
"createdBy": "project:r8",
"createTime": "2021-05-03T17:58:14.746427Z",
"updateTime": "2021-05-03T17:58:15.746318Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "744d742137a82656b8ee27513f975e0528aecd78",
"ref": "refs/heads/main"
}
}
}
[17:58:15.905] Task URL: https://cr-buildbucket.appspot.com/build/8848232758600762320
[17:58:15.905] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:2:0) after 7m20s
[18:05:36.047] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:2:0)
[18:05:36.113] Build status: SCHEDULED
[18:05:36.113] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:3:0) after 1m21s
[18:06:57.279] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:3:0)
[18:06:57.306] Build status: SCHEDULED
[18:06:57.306] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:4:0) after 5m7s
[18:12:04.342] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:4:0)
[18:12:04.372] Build status: SCHEDULED
[18:12:04.372] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:5:0) after 5m47s
[18:17:51.490] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:5:0)
[18:17:51.524] Build status: SCHEDULED
[18:17:51.524] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:6:0) after 9m41s
[18:27:32.607] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:6:0)
[18:27:32.642] Build status: SCHEDULED
[18:27:32.642] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:7:0) after 1m20s
[18:28:52.710] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:7:0)
[18:28:52.757] Build status: SCHEDULED
[18:28:52.757] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:8:0) after 5m27s
[18:34:19.810] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:8:0)
[18:34:19.834] Build status: SCHEDULED
[18:34:19.834] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:9:0) after 1m40s
[18:35:59.930] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:9:0)
[18:35:59.958] Build status: SCHEDULED
[18:35:59.958] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:10:0) after 9m10s
[18:45:10.228] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:10:0)
[18:45:10.260] Build status: SCHEDULED
[18:45:10.260] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:11:0) after 2m3s
[18:46:12.253] Received PubSub notification, asking Buildbucket for the build status
[18:46:12.282] Build status: STARTED
[18:47:13.301] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:11:0)
[18:47:13.346] Build status: STARTED
[18:47:13.346] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:13:0) after 9m53s
[18:57:06.343] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:13:0)
[18:57:06.384] Build status: STARTED
[18:57:06.384] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:14:0) after 6m58s
[19:04:04.569] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:14:0)
[19:04:04.608] Build status: STARTED
[19:04:04.608] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:15:0) after 8m50s
[19:12:54.773] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:15:0)
[19:12:54.803] Build status: STARTED
[19:12:54.803] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:16:0) after 3m41s
[19:16:35.920] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:16:0)
[19:16:35.955] Build status: STARTED
[19:16:35.955] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:17:0) after 9m3s
[19:25:39.092] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:17:0)
[19:25:39.121] Build status: STARTED
[19:25:39.121] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:18:0) after 6m16s
[19:31:55.713] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:18:0)
[19:31:55.740] Build status: STARTED
[19:31:55.740] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:19:0) after 2m2s
[19:33:57.976] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:19:0)
[19:33:58.014] Build status: STARTED
[19:33:58.014] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:20:0) after 3m54s
[19:37:51.994] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:20:0)
[19:37:52.028] Build status: STARTED
[19:37:52.028] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:21:0) after 7m32s
[19:45:24.573] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:21:0)
[19:45:24.615] Build status: STARTED
[19:45:24.615] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:22:0) after 4m0s
[19:49:24.884] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:22:0)
[19:49:25.142] Build status: STARTED
[19:49:25.142] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:23:0) after 5m7s
[19:54:32.250] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:23:0)
[19:54:32.285] Build status: STARTED
[19:54:32.285] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:24:0) after 8m44s
[20:03:16.412] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:24:0)
[20:03:16.502] Build status: STARTED
[20:03:16.502] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:25:0) after 5m19s
[20:08:35.536] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:25:0)
[20:08:35.609] Build status: STARTED
[20:08:35.609] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:26:0) after 5m25s
[20:14:00.659] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:26:0)
[20:14:00.691] Build status: STARTED
[20:14:00.691] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:27:0) after 3m7s
[20:17:07.766] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:27:0)
[20:17:07.811] Build status: STARTED
[20:17:07.811] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:28:0) after 2m22s
[20:19:29.828] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:28:0)
[20:19:29.858] Build status: STARTED
[20:19:29.858] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:29:0) after 4m51s
[20:24:20.874] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:29:0)
[20:24:20.921] Build status: STARTED
[20:24:20.921] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:30:0) after 4m50s
[20:29:10.945] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:30:0)
[20:29:10.988] Build status: STARTED
[20:29:10.988] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:31:0) after 3m55s
[20:33:06.113] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:31:0)
[20:33:06.186] Build status: STARTED
[20:33:06.186] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:32:0) after 4m32s
[20:37:38.840] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:32:0)
[20:37:38.866] Build status: STARTED
[20:37:38.866] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:33:0) after 4m17s
[20:41:56.172] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:33:0)
[20:41:56.203] Build status: STARTED
[20:41:56.203] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:34:0) after 6m56s
[20:48:52.186] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:34:0)
[20:48:52.222] Build status: STARTED
[20:48:52.222] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:35:0) after 8m18s
[20:57:10.372] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:35:0)
[20:57:10.399] Build status: STARTED
[20:57:10.399] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:36:0) after 4m7s
[21:01:17.404] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:36:0)
[21:01:17.438] Build status: STARTED
[21:01:17.438] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:37:0) after 1m3s
[21:02:20.457] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:37:0)
[21:02:20.489] Build status: STARTED
[21:02:20.489] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:38:0) after 7m33s
[21:09:53.473] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:38:0)
[21:09:53.499] Build status: STARTED
[21:09:53.499] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:39:0) after 1m3s
[21:10:56.544] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:39:0)
[21:10:56.583] Build status: STARTED
[21:10:56.583] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:40:0) after 7m0s
[21:17:56.600] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:40:0)
[21:17:56.626] Build status: STARTED
[21:17:56.626] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:41:0) after 7m59s
[21:25:55.742] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:41:0)
[21:25:55.805] Build status: STARTED
[21:25:55.805] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:42:0) after 5m35s
[21:31:30.959] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:42:0)
[21:31:31.005] Build status: STARTED
[21:31:31.005] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9013662820823554288:43:0) after 8m0s
[21:39:17.644] Received PubSub notification, asking Buildbucket for the build status
[21:39:17.721] Build:
{
"id": "8848232758600762320",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-jdk8"
},
"number": 809,
"createdBy": "project:r8",
"createTime": "2021-05-03T17:58:14.746427Z",
"startTime": "2021-05-03T18:46:11.596582Z",
"endTime": "2021-05-03T21:39:16.822293Z",
"updateTime": "2021-05-03T21:39:17.367124Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "744d742137a82656b8ee27513f975e0528aecd78",
"ref": "refs/heads/main"
}
}
}
[21:39:17.721] Invocation finished in 3h41m4.479015947s with status SUCCEEDED