[13:30:56.529] New invocation is queued and will start shortly
[13:30:57.605] Starting the invocation (attempt 1)
[13:30:57.665] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:30:57.665] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:30:57.665] Buildbucket request:
{
"bucket": "luci.r8.ci",
"client_operation_id": "9029171717809463136",
"parameters_json": "{\"builder_name\":\"linux-jdk8\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://r8.googlesource.com/r8\",\"revision\":\"4f7270758d88a050f7543c80a4cc89d6579878b5\"},\"id\":\"https://r8.googlesource.com/r8/+/refs/heads/master@4f7270758d88a050f7543c80a4cc89d6579878b5\",\"title\":\"4f7270758d88a050f7543c80a4cc89d6579878b5\",\"url\":\"https://r8.googlesource.com/r8/+/4f7270758d88a050f7543c80a4cc89d6579878b5\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://r8.googlesource.com/r8\",\"revision\":\"2548d66a0e1b165036175a088d25639c3d3a68e5\"},\"id\":\"https://r8.googlesource.com/r8/+/refs/heads/master@2548d66a0e1b165036175a088d25639c3d3a68e5\",\"title\":\"2548d66a0e1b165036175a088d25639c3d3a68e5\",\"url\":\"https://r8.googlesource.com/r8/+/2548d66a0e1b165036175a088d25639c3d3a68e5\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://r8.googlesource.com/r8\",\"revision\":\"2548d66a0e1b165036175a088d25639c3d3a68e5\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-jdk8",
"scheduler_invocation_id:9029171717809463136",
"scheduler_job_id:r8/linux-jdk8",
"user_agent:luci-scheduler",
"buildset:commit/git/2548d66a0e1b165036175a088d25639c3d3a68e5",
"buildset:commit/gitiles/r8.googlesource.com/r8/+/2548d66a0e1b165036175a088d25639c3d3a68e5",
"gitiles_ref:refs/heads/master"
]
}
[13:30:59.098] Buildbucket response:
{
"build": {
"bucket": "luci.r8.ci",
"canary_preference": "PROD",
"created_by": "project:r8",
"created_ts": "1605274257937637",
"id": "8863741655668126880",
"parameters_json": "{\"builder_name\": \"linux-jdk8\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://r8.googlesource.com/r8\", \"revision\": \"4f7270758d88a050f7543c80a4cc89d6579878b5\"}, \"id\": \"https://r8.googlesource.com/r8/+/refs/heads/master@4f7270758d88a050f7543c80a4cc89d6579878b5\", \"title\": \"4f7270758d88a050f7543c80a4cc89d6579878b5\", \"url\": \"https://r8.googlesource.com/r8/+/4f7270758d88a050f7543c80a4cc89d6579878b5\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://r8.googlesource.com/r8\", \"revision\": \"2548d66a0e1b165036175a088d25639c3d3a68e5\"}, \"id\": \"https://r8.googlesource.com/r8/+/refs/heads/master@2548d66a0e1b165036175a088d25639c3d3a68e5\", \"title\": \"2548d66a0e1b165036175a088d25639c3d3a68e5\", \"url\": \"https://r8.googlesource.com/r8/+/2548d66a0e1b165036175a088d25639c3d3a68e5\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://r8.googlesource.com/r8\", \"revision\": \"2548d66a0e1b165036175a088d25639c3d3a68e5\"}}",
"project": "r8",
"result_details_json": "{\"properties\": {}}",
"service_account": "r8-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1605274258769296",
"tags": [
"build_address:luci.r8.ci/linux-jdk8/142",
"builder:linux-jdk8",
"buildset:commit/git/2548d66a0e1b165036175a088d25639c3d3a68e5",
"buildset:commit/gitiles/r8.googlesource.com/r8/+/2548d66a0e1b165036175a088d25639c3d3a68e5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029171717809463136",
"scheduler_job_id:r8/linux-jdk8",
"swarming_hostname:chrome-swarming.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/r8/buildbucket/cr-buildbucket.appspot.com/8863741655668126880/+/annotations",
"swarming_tag:luci_project:r8",
"swarming_tag:recipe_name:rex",
"swarming_tag:recipe_package:infra_internal/recipe_bundles/chrome-internal.googlesource.com/chrome/tools/build_limited/scripts/slave",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1605274258769420",
"url": "https://ci.chromium.org/b/8863741655668126880",
"utcnow_ts": "1605274259081461"
}
}
[13:30:59.098] Task URL: https://ci.chromium.org/b/8863741655668126880
[13:30:59.098] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:2:0) after 1m0s
[13:31:59.131] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:2:0)
[13:31:59.131] Timer tick, asking Buildbucket for the build status
[13:31:59.265] Build 8863741655668126880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:31:59.265] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:3:0) after 1m0s
[13:32:59.380] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:3:0)
[13:32:59.380] Timer tick, asking Buildbucket for the build status
[13:32:59.465] Build 8863741655668126880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:32:59.465] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:4:0) after 1m0s
[13:33:59.578] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:4:0)
[13:33:59.578] Timer tick, asking Buildbucket for the build status
[13:33:59.794] Build 8863741655668126880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:33:59.794] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:5:0) after 1m0s
[13:34:59.811] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:5:0)
[13:34:59.811] Timer tick, asking Buildbucket for the build status
[13:34:59.878] Build 8863741655668126880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:34:59.878] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:6:0) after 1m0s
[13:35:59.881] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:6:0)
[13:35:59.881] Timer tick, asking Buildbucket for the build status
[13:35:59.996] Build 8863741655668126880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:35:59.996] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:7:0) after 1m0s
[13:37:00.027] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:7:0)
[13:37:00.027] Timer tick, asking Buildbucket for the build status
[13:37:00.320] Build 8863741655668126880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:37:00.320] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:8:0) after 1m0s
[13:38:01.815] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:8:0)
[13:38:01.815] Timer tick, asking Buildbucket for the build status
[13:38:01.895] Build 8863741655668126880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:38:01.895] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:9:0) after 1m0s
[13:39:02.871] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:9:0)
[13:39:02.871] Timer tick, asking Buildbucket for the build status
[13:39:03.116] Build 8863741655668126880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:39:03.116] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:10:0) after 1m0s
[13:40:03.338] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:10:0)
[13:40:03.338] Timer tick, asking Buildbucket for the build status
[13:40:03.446] Build 8863741655668126880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:40:03.446] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:11:0) after 1m0s
[13:41:03.513] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:11:0)
[13:41:03.513] Timer tick, asking Buildbucket for the build status
[13:41:08.322] Build 8863741655668126880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:41:08.322] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:12:0) after 1m0s
[13:42:08.362] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:12:0)
[13:42:08.362] Timer tick, asking Buildbucket for the build status
[13:42:08.676] Build 8863741655668126880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:42:08.676] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:13:0) after 1m0s
[13:43:08.703] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:13:0)
[13:43:08.703] Timer tick, asking Buildbucket for the build status
[13:43:08.861] Build 8863741655668126880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:43:08.861] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:14:0) after 1m0s
[13:44:08.920] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:14:0)
[13:44:08.921] Timer tick, asking Buildbucket for the build status
[13:44:09.918] Build 8863741655668126880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:44:09.918] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:15:0) after 1m0s
[13:45:10.076] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:15:0)
[13:45:10.076] Timer tick, asking Buildbucket for the build status
[13:45:10.212] Build 8863741655668126880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:45:10.212] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:16:0) after 1m0s
[13:45:19.745] Received PubSub notification, asking Buildbucket for the build status
[13:45:19.978] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:10.266] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:16:0)
[13:46:10.266] Timer tick, asking Buildbucket for the build status
[13:46:10.401] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:10.401] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:18:0) after 1m0s
[13:47:10.678] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:18:0)
[13:47:10.678] Timer tick, asking Buildbucket for the build status
[13:47:11.029] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:11.029] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:19:0) after 1m0s
[13:48:11.147] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:19:0)
[13:48:11.147] Timer tick, asking Buildbucket for the build status
[13:48:11.334] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:11.334] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:20:0) after 1m0s
[13:49:11.550] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:20:0)
[13:49:11.550] Timer tick, asking Buildbucket for the build status
[13:49:11.781] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:11.781] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:21:0) after 1m0s
[13:50:11.809] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:21:0)
[13:50:11.809] Timer tick, asking Buildbucket for the build status
[13:50:12.001] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:12.001] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:22:0) after 1m0s
[13:51:12.068] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:22:0)
[13:51:12.068] Timer tick, asking Buildbucket for the build status
[13:51:12.252] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:12.252] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:23:0) after 1m0s
[13:52:12.289] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:23:0)
[13:52:12.289] Timer tick, asking Buildbucket for the build status
[13:52:12.605] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:12.605] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:24:0) after 1m0s
[13:53:12.768] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:24:0)
[13:53:12.768] Timer tick, asking Buildbucket for the build status
[13:53:12.841] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:12.842] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:25:0) after 1m0s
[13:54:13.053] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:25:0)
[13:54:13.053] Timer tick, asking Buildbucket for the build status
[13:54:13.586] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:13.586] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:26:0) after 1m0s
[13:55:13.794] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:26:0)
[13:55:13.794] Timer tick, asking Buildbucket for the build status
[13:55:15.171] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:15.173] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:27:0) after 1m0s
[13:56:15.269] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:27:0)
[13:56:15.269] Timer tick, asking Buildbucket for the build status
[13:56:17.108] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:17.108] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:28:0) after 1m0s
[13:57:17.157] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:28:0)
[13:57:17.157] Timer tick, asking Buildbucket for the build status
[13:57:17.283] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:17.283] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:29:0) after 1m0s
[13:58:17.418] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:29:0)
[13:58:17.418] Timer tick, asking Buildbucket for the build status
[13:58:17.526] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:17.526] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:30:0) after 1m0s
[13:59:17.887] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:30:0)
[13:59:17.887] Timer tick, asking Buildbucket for the build status
[13:59:17.945] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:17.945] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:31:0) after 1m0s
[14:00:17.992] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:31:0)
[14:00:17.992] Timer tick, asking Buildbucket for the build status
[14:00:18.094] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:18.094] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:32:0) after 1m0s
[14:01:18.149] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:32:0)
[14:01:18.149] Timer tick, asking Buildbucket for the build status
[14:01:18.567] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:18.567] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:33:0) after 1m0s
[14:02:18.716] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:33:0)
[14:02:18.716] Timer tick, asking Buildbucket for the build status
[14:02:18.832] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:18.832] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:34:0) after 1m0s
[14:03:19.048] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:34:0)
[14:03:19.048] Timer tick, asking Buildbucket for the build status
[14:03:19.129] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:19.129] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:35:0) after 1m0s
[14:04:19.139] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:35:0)
[14:04:19.139] Timer tick, asking Buildbucket for the build status
[14:04:19.206] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:19.206] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:36:0) after 1m0s
[14:05:19.375] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:36:0)
[14:05:19.375] Timer tick, asking Buildbucket for the build status
[14:05:19.457] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:19.457] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:37:0) after 1m0s
[14:06:19.553] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:37:0)
[14:06:19.553] Timer tick, asking Buildbucket for the build status
[14:06:19.672] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:19.672] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:38:0) after 1m0s
[14:07:19.821] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:38:0)
[14:07:19.821] Timer tick, asking Buildbucket for the build status
[14:07:19.929] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:19.929] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:39:0) after 1m0s
[14:08:20.044] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:39:0)
[14:08:20.044] Timer tick, asking Buildbucket for the build status
[14:08:20.132] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:20.132] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:40:0) after 1m0s
[14:09:20.123] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:40:0)
[14:09:20.123] Timer tick, asking Buildbucket for the build status
[14:09:20.203] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:20.204] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:41:0) after 1m0s
[14:10:20.289] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:41:0)
[14:10:20.289] Timer tick, asking Buildbucket for the build status
[14:10:20.703] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:20.703] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:42:0) after 1m0s
[14:11:20.744] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:42:0)
[14:11:20.744] Timer tick, asking Buildbucket for the build status
[14:11:20.914] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:20.914] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:43:0) after 1m0s
[14:12:21.044] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:43:0)
[14:12:21.044] Timer tick, asking Buildbucket for the build status
[14:12:21.126] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:21.126] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:44:0) after 1m0s
[14:13:21.178] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:44:0)
[14:13:21.178] Timer tick, asking Buildbucket for the build status
[14:13:21.255] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:21.255] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:45:0) after 1m0s
[14:14:21.293] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:45:0)
[14:14:21.293] Timer tick, asking Buildbucket for the build status
[14:14:21.404] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:21.404] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:46:0) after 1m0s
[14:15:21.395] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:46:0)
[14:15:21.395] Timer tick, asking Buildbucket for the build status
[14:15:21.502] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:21.502] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:47:0) after 1m0s
[14:16:21.531] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:47:0)
[14:16:21.531] Timer tick, asking Buildbucket for the build status
[14:16:21.640] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:21.640] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:48:0) after 1m0s
[14:17:21.747] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:48:0)
[14:17:21.747] Timer tick, asking Buildbucket for the build status
[14:17:23.328] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:23.329] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:49:0) after 1m0s
[14:18:23.344] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:49:0)
[14:18:23.344] Timer tick, asking Buildbucket for the build status
[14:18:23.581] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:23.581] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:50:0) after 1m0s
[14:19:23.676] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:50:0)
[14:19:23.676] Timer tick, asking Buildbucket for the build status
[14:19:23.814] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:23.814] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:51:0) after 1m0s
[14:20:23.855] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:51:0)
[14:20:23.855] Timer tick, asking Buildbucket for the build status
[14:20:24.043] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:24.043] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:52:0) after 1m0s
[14:21:24.122] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:52:0)
[14:21:24.122] Timer tick, asking Buildbucket for the build status
[14:21:24.263] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:24.263] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:53:0) after 1m0s
[14:22:24.319] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:53:0)
[14:22:24.319] Timer tick, asking Buildbucket for the build status
[14:22:25.518] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:25.519] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:54:0) after 1m0s
[14:23:25.712] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:54:0)
[14:23:25.712] Timer tick, asking Buildbucket for the build status
[14:23:25.784] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:25.784] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:55:0) after 1m0s
[14:24:25.854] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:55:0)
[14:24:25.854] Timer tick, asking Buildbucket for the build status
[14:24:25.927] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:25.927] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:56:0) after 1m0s
[14:25:25.998] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:56:0)
[14:25:25.998] Timer tick, asking Buildbucket for the build status
[14:25:26.114] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:26.114] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:57:0) after 1m0s
[14:26:26.158] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:57:0)
[14:26:26.158] Timer tick, asking Buildbucket for the build status
[14:26:26.685] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:26.686] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:58:0) after 1m0s
[14:27:26.789] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:58:0)
[14:27:26.789] Timer tick, asking Buildbucket for the build status
[14:27:26.865] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:26.865] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:59:0) after 1m0s
[14:28:26.871] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:59:0)
[14:28:26.871] Timer tick, asking Buildbucket for the build status
[14:28:27.086] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:27.086] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:60:0) after 1m0s
[14:29:27.112] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:60:0)
[14:29:27.112] Timer tick, asking Buildbucket for the build status
[14:29:27.186] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:27.186] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:61:0) after 1m0s
[14:30:27.343] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:61:0)
[14:30:27.343] Timer tick, asking Buildbucket for the build status
[14:30:27.434] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:27.434] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:62:0) after 1m0s
[14:31:27.448] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:62:0)
[14:31:27.448] Timer tick, asking Buildbucket for the build status
[14:31:27.750] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:27.750] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:63:0) after 1m0s
[14:32:27.834] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:63:0)
[14:32:27.834] Timer tick, asking Buildbucket for the build status
[14:32:27.943] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:27.943] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:64:0) after 1m0s
[14:33:27.980] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:64:0)
[14:33:27.980] Timer tick, asking Buildbucket for the build status
[14:33:28.314] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:28.314] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:65:0) after 1m0s
[14:34:28.347] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:65:0)
[14:34:28.347] Timer tick, asking Buildbucket for the build status
[14:34:28.465] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:28.465] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:66:0) after 1m0s
[14:35:28.532] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:66:0)
[14:35:28.532] Timer tick, asking Buildbucket for the build status
[14:35:28.618] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:28.618] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:67:0) after 1m0s
[14:36:28.647] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:67:0)
[14:36:28.647] Timer tick, asking Buildbucket for the build status
[14:36:28.933] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:28.933] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:68:0) after 1m0s
[14:37:28.969] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:68:0)
[14:37:28.969] Timer tick, asking Buildbucket for the build status
[14:37:29.373] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:29.373] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:69:0) after 1m0s
[14:38:29.390] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:69:0)
[14:38:29.390] Timer tick, asking Buildbucket for the build status
[14:38:30.555] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:30.555] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:70:0) after 1m0s
[14:39:30.621] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:70:0)
[14:39:30.621] Timer tick, asking Buildbucket for the build status
[14:39:30.869] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:30.869] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:71:0) after 1m0s
[14:40:30.957] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:71:0)
[14:40:30.957] Timer tick, asking Buildbucket for the build status
[14:40:32.033] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:32.033] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:72:0) after 1m0s
[14:41:32.138] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:72:0)
[14:41:32.138] Timer tick, asking Buildbucket for the build status
[14:41:34.117] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:34.117] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:73:0) after 1m0s
[14:42:34.205] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:73:0)
[14:42:34.205] Timer tick, asking Buildbucket for the build status
[14:42:34.408] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:34.408] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:74:0) after 1m0s
[14:43:34.582] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:74:0)
[14:43:34.582] Timer tick, asking Buildbucket for the build status
[14:43:34.665] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:34.665] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:75:0) after 1m0s
[14:44:34.828] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:75:0)
[14:44:34.828] Timer tick, asking Buildbucket for the build status
[14:44:35.030] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:35.030] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:76:0) after 1m0s
[14:45:36.902] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:76:0)
[14:45:36.902] Timer tick, asking Buildbucket for the build status
[14:45:37.027] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:37.027] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:77:0) after 1m0s
[14:46:37.040] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:77:0)
[14:46:37.040] Timer tick, asking Buildbucket for the build status
[14:46:37.156] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:37.157] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:78:0) after 1m0s
[14:47:37.206] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:78:0)
[14:47:37.206] Timer tick, asking Buildbucket for the build status
[14:47:37.358] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:37.358] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:79:0) after 1m0s
[14:48:37.429] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:79:0)
[14:48:37.429] Timer tick, asking Buildbucket for the build status
[14:48:37.491] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:37.491] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:80:0) after 1m0s
[14:49:37.600] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:80:0)
[14:49:37.600] Timer tick, asking Buildbucket for the build status
[14:49:38.529] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:38.529] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:81:0) after 1m0s
[14:50:38.573] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:81:0)
[14:50:38.574] Timer tick, asking Buildbucket for the build status
[14:50:39.059] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:39.059] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:82:0) after 1m0s
[14:51:39.063] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:82:0)
[14:51:39.063] Timer tick, asking Buildbucket for the build status
[14:51:39.195] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:39.195] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:83:0) after 1m0s
[14:52:39.238] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:83:0)
[14:52:39.238] Timer tick, asking Buildbucket for the build status
[14:52:39.475] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:39.475] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:84:0) after 1m0s
[14:53:39.534] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:84:0)
[14:53:39.534] Timer tick, asking Buildbucket for the build status
[14:53:39.617] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:39.617] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:85:0) after 1m0s
[14:54:39.806] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:85:0)
[14:54:39.806] Timer tick, asking Buildbucket for the build status
[14:54:39.888] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:39.888] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:86:0) after 1m0s
[14:55:39.955] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:86:0)
[14:55:39.955] Timer tick, asking Buildbucket for the build status
[14:55:40.028] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:40.028] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:87:0) after 1m0s
[14:56:40.218] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:87:0)
[14:56:40.218] Timer tick, asking Buildbucket for the build status
[14:56:40.867] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:40.867] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:88:0) after 1m0s
[14:57:41.125] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:88:0)
[14:57:41.126] Timer tick, asking Buildbucket for the build status
[14:57:42.904] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:42.904] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:89:0) after 1m0s
[14:58:43.135] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:89:0)
[14:58:43.135] Timer tick, asking Buildbucket for the build status
[14:58:43.256] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:43.256] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:90:0) after 1m0s
[14:59:43.406] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:90:0)
[14:59:43.406] Timer tick, asking Buildbucket for the build status
[14:59:43.521] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:43.522] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:91:0) after 1m0s
[15:00:43.595] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:91:0)
[15:00:43.595] Timer tick, asking Buildbucket for the build status
[15:00:44.271] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:44.271] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:92:0) after 1m0s
[15:01:44.440] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:92:0)
[15:01:44.440] Timer tick, asking Buildbucket for the build status
[15:01:44.587] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:44.587] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:93:0) after 1m0s
[15:02:44.632] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:93:0)
[15:02:44.632] Timer tick, asking Buildbucket for the build status
[15:02:44.945] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:44.946] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:94:0) after 1m0s
[15:03:45.008] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:94:0)
[15:03:45.008] Timer tick, asking Buildbucket for the build status
[15:03:45.374] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:45.374] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:95:0) after 1m0s
[15:04:45.462] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:95:0)
[15:04:45.462] Timer tick, asking Buildbucket for the build status
[15:04:45.575] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:45.575] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:96:0) after 1m0s
[15:05:45.756] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:96:0)
[15:05:45.756] Timer tick, asking Buildbucket for the build status
[15:05:45.950] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:45.950] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:97:0) after 1m0s
[15:06:45.990] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:97:0)
[15:06:45.990] Timer tick, asking Buildbucket for the build status
[15:06:46.293] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:46.293] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:98:0) after 1m0s
[15:07:46.318] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:98:0)
[15:07:46.318] Timer tick, asking Buildbucket for the build status
[15:07:47.487] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:47.487] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:99:0) after 1m0s
[15:08:47.620] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:99:0)
[15:08:47.620] Timer tick, asking Buildbucket for the build status
[15:08:47.705] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:47.705] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:100:0) after 1m0s
[15:09:47.821] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:100:0)
[15:09:47.821] Timer tick, asking Buildbucket for the build status
[15:09:47.891] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:47.891] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:101:0) after 1m0s
[15:10:47.942] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:101:0)
[15:10:47.942] Timer tick, asking Buildbucket for the build status
[15:10:48.071] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:48.071] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:102:0) after 1m0s
[15:11:48.166] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:102:0)
[15:11:48.166] Timer tick, asking Buildbucket for the build status
[15:11:48.610] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:48.610] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:103:0) after 1m0s
[15:12:48.724] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:103:0)
[15:12:48.724] Timer tick, asking Buildbucket for the build status
[15:12:48.799] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:48.799] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:104:0) after 1m0s
[15:13:48.830] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:104:0)
[15:13:48.830] Timer tick, asking Buildbucket for the build status
[15:13:48.901] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:48.901] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:105:0) after 1m0s
[15:14:48.933] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:105:0)
[15:14:48.933] Timer tick, asking Buildbucket for the build status
[15:14:49.019] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:49.019] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:106:0) after 1m0s
[15:15:49.066] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:106:0)
[15:15:49.066] Timer tick, asking Buildbucket for the build status
[15:15:49.162] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:49.163] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:107:0) after 1m0s
[15:16:49.212] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:107:0)
[15:16:49.212] Timer tick, asking Buildbucket for the build status
[15:16:49.412] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:49.412] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:108:0) after 1m0s
[15:17:49.527] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:108:0)
[15:17:49.527] Timer tick, asking Buildbucket for the build status
[15:17:49.717] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:49.717] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:109:0) after 1m0s
[15:18:49.782] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:109:0)
[15:18:49.782] Timer tick, asking Buildbucket for the build status
[15:18:49.878] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:49.878] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:110:0) after 1m0s
[15:19:49.994] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:110:0)
[15:19:49.994] Timer tick, asking Buildbucket for the build status
[15:19:50.105] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:50.105] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:111:0) after 1m0s
[15:20:50.149] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:111:0)
[15:20:50.149] Timer tick, asking Buildbucket for the build status
[15:20:50.434] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:50.434] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:112:0) after 1m0s
[15:21:50.608] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:112:0)
[15:21:50.608] Timer tick, asking Buildbucket for the build status
[15:21:50.743] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:50.743] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:113:0) after 1m0s
[15:22:50.768] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:113:0)
[15:22:50.768] Timer tick, asking Buildbucket for the build status
[15:22:50.841] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:50.841] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:114:0) after 1m0s
[15:23:50.915] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:114:0)
[15:23:50.915] Timer tick, asking Buildbucket for the build status
[15:23:51.033] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:51.033] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:115:0) after 1m0s
[15:24:51.080] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:115:0)
[15:24:51.080] Timer tick, asking Buildbucket for the build status
[15:24:51.922] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:51.922] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:116:0) after 1m0s
[15:25:51.978] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:116:0)
[15:25:51.979] Timer tick, asking Buildbucket for the build status
[15:25:52.063] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:52.063] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:117:0) after 1m0s
[15:26:52.114] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:117:0)
[15:26:52.114] Timer tick, asking Buildbucket for the build status
[15:26:52.824] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:52.824] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:118:0) after 1m0s
[15:27:52.892] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:118:0)
[15:27:52.892] Timer tick, asking Buildbucket for the build status
[15:27:52.964] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:52.964] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:119:0) after 1m0s
[15:28:53.076] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:119:0)
[15:28:53.076] Timer tick, asking Buildbucket for the build status
[15:28:53.150] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:53.151] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:120:0) after 1m0s
[15:29:53.167] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:120:0)
[15:29:53.167] Timer tick, asking Buildbucket for the build status
[15:29:53.244] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:53.244] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:121:0) after 1m0s
[15:30:53.268] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:121:0)
[15:30:53.268] Timer tick, asking Buildbucket for the build status
[15:30:53.777] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:53.777] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:122:0) after 1m0s
[15:31:53.830] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:122:0)
[15:31:53.830] Timer tick, asking Buildbucket for the build status
[15:31:54.178] Build 8863741655668126880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:54.178] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9029171717809463136:123:0) after 1m0s
[15:31:59.605] Received PubSub notification, asking Buildbucket for the build status
[15:31:59.699] Build 8863741655668126880: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[15:31:59.699] Buildbucket build:
{
"build": {
"bucket": "luci.r8.ci",
"canary_preference": "PROD",
"completed_ts": "1605281518927137",
"created_by": "project:r8",
"created_ts": "1605274257937637",
"failure_reason": "BUILD_FAILURE",
"id": "8863741655668126880",
"parameters_json": "{\"builder_name\": \"linux-jdk8\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://r8.googlesource.com/r8\", \"revision\": \"4f7270758d88a050f7543c80a4cc89d6579878b5\"}, \"id\": \"https://r8.googlesource.com/r8/+/refs/heads/master@4f7270758d88a050f7543c80a4cc89d6579878b5\", \"title\": \"4f7270758d88a050f7543c80a4cc89d6579878b5\", \"url\": \"https://r8.googlesource.com/r8/+/4f7270758d88a050f7543c80a4cc89d6579878b5\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://r8.googlesource.com/r8\", \"revision\": \"2548d66a0e1b165036175a088d25639c3d3a68e5\"}, \"id\": \"https://r8.googlesource.com/r8/+/refs/heads/master@2548d66a0e1b165036175a088d25639c3d3a68e5\", \"title\": \"2548d66a0e1b165036175a088d25639c3d3a68e5\", \"url\": \"https://r8.googlesource.com/r8/+/2548d66a0e1b165036175a088d25639c3d3a68e5\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://r8.googlesource.com/r8\", \"revision\": \"2548d66a0e1b165036175a088d25639c3d3a68e5\"}}",
"project": "r8",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step('Run tests') (retcode: 1)\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://r8.googlesource.com/r8\", \"revision\": \"4f7270758d88a050f7543c80a4cc89d6579878b5\"}, \"id\": \"https://r8.googlesource.com/r8/+/refs/heads/master@4f7270758d88a050f7543c80a4cc89d6579878b5\", \"title\": \"4f7270758d88a050f7543c80a4cc89d6579878b5\", \"url\": \"https://r8.googlesource.com/r8/+/4f7270758d88a050f7543c80a4cc89d6579878b5\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://r8.googlesource.com/r8\", \"revision\": \"2548d66a0e1b165036175a088d25639c3d3a68e5\"}, \"id\": \"https://r8.googlesource.com/r8/+/refs/heads/master@2548d66a0e1b165036175a088d25639c3d3a68e5\", \"title\": \"2548d66a0e1b165036175a088d25639c3d3a68e5\", \"url\": \"https://r8.googlesource.com/r8/+/2548d66a0e1b165036175a088d25639c3d3a68e5\"}]}, \"bot_id\": \"luci-r8-custom-ci-xenial-12-zfxz\", \"branch\": \"refs/heads/master\", \"builder_group\": \"internal.client.r8\", \"buildername\": \"linux-jdk8\", \"buildnumber\": 142, \"path_config\": \"generic\", \"recipe\": \"rex\", \"repository\": \"https://r8.googlesource.com/r8\", \"revision\": \"2548d66a0e1b165036175a088d25639c3d3a68e5\", \"test_options\": [\"--runtimes=jdk8\", \"--no_internal\", \"--one_line_per_test\", \"--archive_failures\"]}, \"ui\": {\"info\": \"Step('Run tests') (retcode: 1)\"}}",
"service_account": "r8-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1605275119284718",
"status": "COMPLETED",
"status_changed_ts": "1605274258769296",
"tags": [
"build_address:luci.r8.ci/linux-jdk8/142",
"builder:linux-jdk8",
"buildset:commit/git/2548d66a0e1b165036175a088d25639c3d3a68e5",
"buildset:commit/gitiles/r8.googlesource.com/r8/+/2548d66a0e1b165036175a088d25639c3d3a68e5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029171717809463136",
"scheduler_job_id:r8/linux-jdk8",
"swarming_hostname:chrome-swarming.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/r8/buildbucket/cr-buildbucket.appspot.com/8863741655668126880/+/annotations",
"swarming_tag:luci_project:r8",
"swarming_tag:recipe_name:rex",
"swarming_tag:recipe_package:infra_internal/recipe_bundles/chrome-internal.googlesource.com/chrome/tools/build_limited/scripts/slave",
"swarming_task_id:4fda9edebdbddc10",
"user_agent:luci-scheduler"
],
"updated_ts": "1605281519251059",
"url": "https://ci.chromium.org/b/8863741655668126880",
"utcnow_ts": "1605281519684662"
}
}
[15:31:59.700] Invocation finished in 2h1m3.188067745s with status FAILED