[12:34:00.089] New invocation is queued and will start shortly
[12:34:01.217] Starting the invocation (attempt 1)
[12:34:01.253] Popped buildset tag "buildset:commit/gitiles/r8.googlesource.com/r8/+/3bdde69172f82f0afb41b44aba49b870cb7075cd"
[12:34:01.253] Popped gitiles commit info from properties and tags
[12:34:01.253] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:34:01.254] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:34:01.254] Buildbucket request:
{
"requestId": "8964670260127635072",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-jdk8"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8964670260127635072",
"job": "r8/linux-jdk8",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "3bdde69172f82f0afb41b44aba49b870cb7075cd"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@3bdde69172f82f0afb41b44aba49b870cb7075cd",
"title": "3bdde69172f82f0afb41b44aba49b870cb7075cd",
"url": "https://r8.googlesource.com/r8/+/3bdde69172f82f0afb41b44aba49b870cb7075cd"
}
]
}
},
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "3bdde69172f82f0afb41b44aba49b870cb7075cd",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8964670260127635072"
},
{
"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"
}
}
[12:34:01.722] Scheduled build:
{
"id": "8799240198145795313",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-jdk8"
},
"number": 2930,
"createdBy": "project:r8",
"createTime": "2022-10-26T12:34:01.346084229Z",
"updateTime": "2022-10-26T12:34:01.346084229Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "3bdde69172f82f0afb41b44aba49b870cb7075cd",
"ref": "refs/heads/main"
}
}
}
[12:34:01.722] Task URL: https://cr-buildbucket.appspot.com/build/8799240198145795313
[12:34:01.722] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:2:0) after 5m13s
[12:39:14.743] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:2:0)
[12:39:14.772] Build status: SCHEDULED
[12:39:14.772] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:3:0) after 5m15s
[12:44:29.792] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:3:0)
[12:44:29.828] Build status: SCHEDULED
[12:44:29.828] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:4:0) after 5m7s
[12:49:36.879] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:4:0)
[12:49:36.945] Build status: SCHEDULED
[12:49:36.945] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:5:0) after 6m40s
[12:56:17.034] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:5:0)
[12:56:17.058] Build status: SCHEDULED
[12:56:17.058] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:6:0) after 9m11s
[13:05:28.094] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:6:0)
[13:05:28.181] Build status: SCHEDULED
[13:05:28.181] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:7:0) after 8m3s
[13:13:31.209] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:7:0)
[13:13:31.249] Build status: SCHEDULED
[13:13:31.249] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:8:0) after 9m9s
[13:18:25.203] Received PubSub notification, asking Buildbucket for the build status
[13:18:25.227] Build status: STARTED
[13:22:40.322] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:8:0)
[13:22:40.349] Build status: STARTED
[13:22:40.349] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:10:0) after 6m7s
[13:28:47.374] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:10:0)
[13:28:47.399] Build status: STARTED
[13:28:47.399] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:11:0) after 9m46s
[13:38:33.561] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:11:0)
[13:38:33.631] Build status: STARTED
[13:38:33.631] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:12:0) after 3m41s
[13:42:14.664] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:12:0)
[13:42:14.688] Build status: STARTED
[13:42:14.688] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:13:0) after 7m34s
[13:49:48.818] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:13:0)
[13:49:48.849] Build status: STARTED
[13:49:48.850] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:14:0) after 9m59s
[13:59:48.117] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:14:0)
[13:59:48.141] Build status: STARTED
[13:59:48.141] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:15:0) after 8m22s
[14:08:10.276] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:15:0)
[14:08:10.302] Build status: STARTED
[14:08:10.302] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:16:0) after 2m19s
[14:10:29.321] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:16:0)
[14:10:29.367] Build status: STARTED
[14:10:29.367] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:17:0) after 3m17s
[14:13:46.702] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:17:0)
[14:13:46.736] Build status: STARTED
[14:13:46.736] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:18:0) after 2m34s
[14:16:20.912] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:18:0)
[14:16:21.016] Build status: STARTED
[14:16:21.016] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:19:0) after 3m46s
[14:20:07.069] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:19:0)
[14:20:07.094] Build status: STARTED
[14:20:07.094] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:20:0) after 8m33s
[14:28:40.793] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:20:0)
[14:28:40.816] Build status: STARTED
[14:28:40.816] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:21:0) after 5m39s
[14:34:19.809] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:21:0)
[14:34:19.833] Build status: STARTED
[14:34:19.833] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:22:0) after 6m42s
[14:41:02.325] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:22:0)
[14:41:02.352] Build status: STARTED
[14:41:02.352] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:23:0) after 2m45s
[14:43:47.477] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:23:0)
[14:43:47.502] Build status: STARTED
[14:43:47.502] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:24:0) after 5m32s
[14:49:19.522] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:24:0)
[14:49:19.545] Build status: STARTED
[14:49:19.545] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:25:0) after 8m3s
[14:57:22.568] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:25:0)
[14:57:22.602] Build status: STARTED
[14:57:22.602] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:26:0) after 4m43s
[15:02:05.938] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:26:0)
[15:02:06.066] Build status: STARTED
[15:02:06.066] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:27:0) after 4m32s
[15:06:38.354] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:27:0)
[15:06:38.376] Build status: STARTED
[15:06:38.376] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:28:0) after 4m19s
[15:10:57.412] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:28:0)
[15:10:57.434] Build status: STARTED
[15:10:57.434] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:29:0) after 1m22s
[15:12:19.460] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:29:0)
[15:12:19.504] Build status: STARTED
[15:12:19.504] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:30:0) after 3m38s
[15:15:57.602] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:30:0)
[15:15:57.633] Build status: STARTED
[15:15:57.633] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:31:0) after 7m51s
[15:23:49.039] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:31:0)
[15:23:49.083] Build status: STARTED
[15:23:49.083] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:32:0) after 6m59s
[15:30:49.368] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:32:0)
[15:30:49.489] Build status: STARTED
[15:30:49.489] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:33:0) after 2m49s
[15:33:38.510] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:33:0)
[15:33:38.533] Build status: STARTED
[15:33:38.533] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:8964670260127635072:34:0) after 7m47s
[15:35:42.678] Received PubSub notification, asking Buildbucket for the build status
[15:35:42.713] Build:
{
"id": "8799240198145795313",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-jdk8"
},
"number": 2930,
"createdBy": "project:r8",
"createTime": "2022-10-26T12:34:01.346084229Z",
"startTime": "2022-10-26T13:18:24.690477Z",
"endTime": "2022-10-26T15:35:42.451038881Z",
"updateTime": "2022-10-26T15:35:42.451038881Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "3bdde69172f82f0afb41b44aba49b870cb7075cd",
"ref": "refs/heads/main"
}
}
}
[15:35:42.713] Invocation finished in 3h1m42.639859504s with status FAILED