[15:27:04.087] New invocation is queued and will start shortly
[15:27:05.196] Starting the invocation (attempt 1)
[15:27:05.250] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:27:05.250] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:27:05.250] Buildbucket request:
{
"bucket": "luci.r8.ci",
"client_operation_id": "9022913221120766528",
"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\":\"6a1efd59003001a8e02f744cca769b65fde9ef13\"},\"id\":\"https://r8.googlesource.com/r8/+/refs/heads/master@6a1efd59003001a8e02f744cca769b65fde9ef13\",\"title\":\"6a1efd59003001a8e02f744cca769b65fde9ef13\",\"url\":\"https://r8.googlesource.com/r8/+/6a1efd59003001a8e02f744cca769b65fde9ef13\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://r8.googlesource.com/r8\",\"revision\":\"ad5218afc174624f39b518025522fe36dcffd2b5\"},\"id\":\"https://r8.googlesource.com/r8/+/refs/heads/master@ad5218afc174624f39b518025522fe36dcffd2b5\",\"title\":\"ad5218afc174624f39b518025522fe36dcffd2b5\",\"url\":\"https://r8.googlesource.com/r8/+/ad5218afc174624f39b518025522fe36dcffd2b5\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://r8.googlesource.com/r8\",\"revision\":\"ad5218afc174624f39b518025522fe36dcffd2b5\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-jdk8",
"scheduler_invocation_id:9022913221120766528",
"scheduler_job_id:r8/linux-jdk8",
"user_agent:luci-scheduler",
"buildset:commit/git/ad5218afc174624f39b518025522fe36dcffd2b5",
"buildset:commit/gitiles/r8.googlesource.com/r8/+/ad5218afc174624f39b518025522fe36dcffd2b5",
"gitiles_ref:refs/heads/master"
]
}
[15:27:08.428] Buildbucket response:
{
"build": {
"bucket": "luci.r8.ci",
"canary_preference": "PROD",
"created_by": "project:r8",
"created_ts": "1611242825489805",
"id": "8857483158978006864",
"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\": \"6a1efd59003001a8e02f744cca769b65fde9ef13\"}, \"id\": \"https://r8.googlesource.com/r8/+/refs/heads/master@6a1efd59003001a8e02f744cca769b65fde9ef13\", \"title\": \"6a1efd59003001a8e02f744cca769b65fde9ef13\", \"url\": \"https://r8.googlesource.com/r8/+/6a1efd59003001a8e02f744cca769b65fde9ef13\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://r8.googlesource.com/r8\", \"revision\": \"ad5218afc174624f39b518025522fe36dcffd2b5\"}, \"id\": \"https://r8.googlesource.com/r8/+/refs/heads/master@ad5218afc174624f39b518025522fe36dcffd2b5\", \"title\": \"ad5218afc174624f39b518025522fe36dcffd2b5\", \"url\": \"https://r8.googlesource.com/r8/+/ad5218afc174624f39b518025522fe36dcffd2b5\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://r8.googlesource.com/r8\", \"revision\": \"ad5218afc174624f39b518025522fe36dcffd2b5\"}}",
"project": "r8",
"result_details_json": "{\"properties\": {}}",
"service_account": "r8-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611242827886490",
"tags": [
"build_address:luci.r8.ci/linux-jdk8/373",
"builder:linux-jdk8",
"buildset:commit/git/ad5218afc174624f39b518025522fe36dcffd2b5",
"buildset:commit/gitiles/r8.googlesource.com/r8/+/ad5218afc174624f39b518025522fe36dcffd2b5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022913221120766528",
"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/8857483158978006864/+/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": "1611242827886729",
"url": "https://ci.chromium.org/b/8857483158978006864",
"utcnow_ts": "1611242828392987"
}
}
[15:27:08.428] Task URL: https://ci.chromium.org/b/8857483158978006864
[15:27:08.428] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:2:0) after 1m0s
[15:28:08.486] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:2:0)
[15:28:08.486] Timer tick, asking Buildbucket for the build status
[15:28:08.610] Build 8857483158978006864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:28:08.611] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:3:0) after 1m0s
[15:29:08.690] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:3:0)
[15:29:08.690] Timer tick, asking Buildbucket for the build status
[15:29:08.770] Build 8857483158978006864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:29:08.770] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:4:0) after 1m0s
[15:30:08.845] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:4:0)
[15:30:08.845] Timer tick, asking Buildbucket for the build status
[15:30:08.986] Build 8857483158978006864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:30:08.986] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:5:0) after 1m0s
[15:31:09.082] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:5:0)
[15:31:09.082] Timer tick, asking Buildbucket for the build status
[15:31:09.168] Build 8857483158978006864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:31:09.168] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:6:0) after 1m0s
[15:32:09.352] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:6:0)
[15:32:09.352] Timer tick, asking Buildbucket for the build status
[15:32:09.461] Build 8857483158978006864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:32:09.461] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:7:0) after 1m0s
[15:32:11.197] Received PubSub notification, asking Buildbucket for the build status
[15:32:11.392] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:09.572] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:7:0)
[15:33:09.572] Timer tick, asking Buildbucket for the build status
[15:33:09.704] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:09.704] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:9:0) after 1m0s
[15:34:09.761] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:9:0)
[15:34:09.761] Timer tick, asking Buildbucket for the build status
[15:34:10.010] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:10.010] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:10:0) after 1m0s
[15:35:10.048] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:10:0)
[15:35:10.048] Timer tick, asking Buildbucket for the build status
[15:35:10.161] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:10.161] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:11:0) after 1m0s
[15:36:10.258] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:11:0)
[15:36:10.258] Timer tick, asking Buildbucket for the build status
[15:36:10.328] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:10.328] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:12:0) after 1m0s
[15:37:10.344] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:12:0)
[15:37:10.344] Timer tick, asking Buildbucket for the build status
[15:37:10.648] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:10.648] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:13:0) after 1m0s
[15:38:10.726] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:13:0)
[15:38:10.726] Timer tick, asking Buildbucket for the build status
[15:38:10.797] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:10.797] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:14:0) after 1m0s
[15:39:10.816] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:14:0)
[15:39:10.816] Timer tick, asking Buildbucket for the build status
[15:39:11.125] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:11.125] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:15:0) after 1m0s
[15:40:11.200] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:15:0)
[15:40:11.200] Timer tick, asking Buildbucket for the build status
[15:40:11.381] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:11.381] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:16:0) after 1m0s
[15:41:11.517] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:16:0)
[15:41:11.518] Timer tick, asking Buildbucket for the build status
[15:41:11.939] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:11.939] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:17:0) after 1m0s
[15:42:11.994] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:17:0)
[15:42:11.994] Timer tick, asking Buildbucket for the build status
[15:42:12.085] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:12.085] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:18:0) after 1m0s
[15:43:12.105] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:18:0)
[15:43:12.105] Timer tick, asking Buildbucket for the build status
[15:43:12.625] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:12.625] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:19:0) after 1m0s
[15:44:12.661] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:19:0)
[15:44:12.661] Timer tick, asking Buildbucket for the build status
[15:44:12.735] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:12.735] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:20:0) after 1m0s
[15:45:12.762] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:20:0)
[15:45:12.762] Timer tick, asking Buildbucket for the build status
[15:45:12.939] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:12.939] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:21:0) after 1m0s
[15:46:12.979] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:21:0)
[15:46:12.979] Timer tick, asking Buildbucket for the build status
[15:46:13.213] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:13.213] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:22:0) after 1m0s
[15:47:13.273] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:22:0)
[15:47:13.273] Timer tick, asking Buildbucket for the build status
[15:47:13.474] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:13.474] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:23:0) after 1m0s
[15:48:13.546] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:23:0)
[15:48:13.546] Timer tick, asking Buildbucket for the build status
[15:48:13.629] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:13.629] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:24:0) after 1m0s
[15:49:13.645] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:24:0)
[15:49:13.645] Timer tick, asking Buildbucket for the build status
[15:49:13.707] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:13.707] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:25:0) after 1m0s
[15:50:13.812] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:25:0)
[15:50:13.812] Timer tick, asking Buildbucket for the build status
[15:50:13.882] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:13.882] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:26:0) after 1m0s
[15:51:13.869] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:26:0)
[15:51:13.869] Timer tick, asking Buildbucket for the build status
[15:51:13.967] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:13.967] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:27:0) after 1m0s
[15:52:14.141] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:27:0)
[15:52:14.141] Timer tick, asking Buildbucket for the build status
[15:52:14.818] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:14.818] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:28:0) after 1m0s
[15:53:14.958] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:28:0)
[15:53:14.958] Timer tick, asking Buildbucket for the build status
[15:53:15.024] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:15.024] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:29:0) after 1m0s
[15:54:16.377] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:29:0)
[15:54:16.377] Timer tick, asking Buildbucket for the build status
[15:54:16.445] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:16.445] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:30:0) after 1m0s
[15:55:18.417] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:30:0)
[15:55:18.417] Timer tick, asking Buildbucket for the build status
[15:55:18.482] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:18.482] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:31:0) after 1m0s
[15:56:18.502] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:31:0)
[15:56:18.502] Timer tick, asking Buildbucket for the build status
[15:56:18.974] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:18.974] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:32:0) after 1m0s
[15:57:19.073] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:32:0)
[15:57:19.073] Timer tick, asking Buildbucket for the build status
[15:57:19.327] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:19.327] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:33:0) after 1m0s
[15:58:19.364] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:33:0)
[15:58:19.364] Timer tick, asking Buildbucket for the build status
[15:58:19.450] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:19.450] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:34:0) after 1m0s
[15:59:19.499] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:34:0)
[15:59:19.499] Timer tick, asking Buildbucket for the build status
[15:59:19.587] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:19.587] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:35:0) after 1m0s
[16:00:20.791] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:35:0)
[16:00:20.791] Timer tick, asking Buildbucket for the build status
[16:00:21.054] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:21.054] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:36:0) after 1m0s
[16:01:21.906] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:36:0)
[16:01:21.906] Timer tick, asking Buildbucket for the build status
[16:01:21.991] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:21.991] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:37:0) after 1m0s
[16:02:22.277] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:37:0)
[16:02:22.277] Timer tick, asking Buildbucket for the build status
[16:02:22.389] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:22.389] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:38:0) after 1m0s
[16:03:22.579] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:38:0)
[16:03:22.579] Timer tick, asking Buildbucket for the build status
[16:03:22.651] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:22.651] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:39:0) after 1m0s
[16:04:22.888] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:39:0)
[16:04:22.888] Timer tick, asking Buildbucket for the build status
[16:04:23.006] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:23.006] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:40:0) after 1m0s
[16:05:23.617] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:40:0)
[16:05:23.617] Timer tick, asking Buildbucket for the build status
[16:05:23.850] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:23.850] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:41:0) after 1m0s
[16:06:24.165] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:41:0)
[16:06:24.165] Timer tick, asking Buildbucket for the build status
[16:06:24.434] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:24.434] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:42:0) after 1m0s
[16:07:24.853] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:42:0)
[16:07:24.853] Timer tick, asking Buildbucket for the build status
[16:07:24.954] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:24.954] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:43:0) after 1m0s
[16:08:25.104] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:43:0)
[16:08:25.104] Timer tick, asking Buildbucket for the build status
[16:08:25.187] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:25.187] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:44:0) after 1m0s
[16:09:25.338] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:44:0)
[16:09:25.338] Timer tick, asking Buildbucket for the build status
[16:09:25.409] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:25.409] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:45:0) after 1m0s
[16:10:25.533] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:45:0)
[16:10:25.533] Timer tick, asking Buildbucket for the build status
[16:10:25.633] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:25.633] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:46:0) after 1m0s
[16:11:25.696] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:46:0)
[16:11:25.696] Timer tick, asking Buildbucket for the build status
[16:11:25.758] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:25.758] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:47:0) after 1m0s
[16:12:25.812] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:47:0)
[16:12:25.812] Timer tick, asking Buildbucket for the build status
[16:12:26.013] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:26.013] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:48:0) after 1m0s
[16:13:26.086] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:48:0)
[16:13:26.086] Timer tick, asking Buildbucket for the build status
[16:13:27.506] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:27.506] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:49:0) after 1m0s
[16:14:27.626] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:49:0)
[16:14:27.626] Timer tick, asking Buildbucket for the build status
[16:14:27.892] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:27.892] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:50:0) after 1m0s
[16:15:27.928] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:50:0)
[16:15:27.928] Timer tick, asking Buildbucket for the build status
[16:15:28.003] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:28.003] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:51:0) after 1m0s
[16:16:28.046] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:51:0)
[16:16:28.046] Timer tick, asking Buildbucket for the build status
[16:16:28.101] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:28.101] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:52:0) after 1m0s
[16:17:28.173] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:52:0)
[16:17:28.174] Timer tick, asking Buildbucket for the build status
[16:17:28.395] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:28.395] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:53:0) after 1m0s
[16:18:28.699] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:53:0)
[16:18:28.699] Timer tick, asking Buildbucket for the build status
[16:18:28.765] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:28.765] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:54:0) after 1m0s
[16:19:28.874] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:54:0)
[16:19:28.874] Timer tick, asking Buildbucket for the build status
[16:19:28.941] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:28.941] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:55:0) after 1m0s
[16:20:29.338] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:55:0)
[16:20:29.338] Timer tick, asking Buildbucket for the build status
[16:20:29.646] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:29.646] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:56:0) after 1m0s
[16:21:29.796] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:56:0)
[16:21:29.796] Timer tick, asking Buildbucket for the build status
[16:21:29.949] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:29.949] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:57:0) after 1m0s
[16:22:29.981] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:57:0)
[16:22:29.981] Timer tick, asking Buildbucket for the build status
[16:22:30.144] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:30.144] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:58:0) after 1m0s
[16:23:30.179] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:58:0)
[16:23:30.179] Timer tick, asking Buildbucket for the build status
[16:23:30.246] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:30.246] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:59:0) after 1m0s
[16:24:31.071] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:59:0)
[16:24:31.071] Timer tick, asking Buildbucket for the build status
[16:24:31.147] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:31.147] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:60:0) after 1m0s
[16:25:31.303] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:60:0)
[16:25:31.303] Timer tick, asking Buildbucket for the build status
[16:25:31.640] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:31.640] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:61:0) after 1m0s
[16:26:31.754] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:61:0)
[16:26:31.754] Timer tick, asking Buildbucket for the build status
[16:26:31.816] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:31.816] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:62:0) after 1m0s
[16:27:31.808] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:62:0)
[16:27:31.808] Timer tick, asking Buildbucket for the build status
[16:27:31.949] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:31.949] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:63:0) after 1m0s
[16:28:32.004] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:63:0)
[16:28:32.004] Timer tick, asking Buildbucket for the build status
[16:28:32.159] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:32.159] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:64:0) after 1m0s
[16:29:32.171] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:64:0)
[16:29:32.171] Timer tick, asking Buildbucket for the build status
[16:29:32.524] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:32.524] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:65:0) after 1m0s
[16:30:32.579] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:65:0)
[16:30:32.579] Timer tick, asking Buildbucket for the build status
[16:30:33.357] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:33.358] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:66:0) after 1m0s
[16:31:33.473] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:66:0)
[16:31:33.473] Timer tick, asking Buildbucket for the build status
[16:31:33.559] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:33.559] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:67:0) after 1m0s
[16:32:33.726] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:67:0)
[16:32:33.726] Timer tick, asking Buildbucket for the build status
[16:32:33.952] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:33.952] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:68:0) after 1m0s
[16:33:34.284] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:68:0)
[16:33:34.284] Timer tick, asking Buildbucket for the build status
[16:33:34.369] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:34.369] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:69:0) after 1m0s
[16:34:34.511] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:69:0)
[16:34:34.511] Timer tick, asking Buildbucket for the build status
[16:34:34.588] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:34.588] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:70:0) after 1m0s
[16:35:34.847] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:70:0)
[16:35:34.847] Timer tick, asking Buildbucket for the build status
[16:35:34.929] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:34.929] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:71:0) after 1m0s
[16:36:35.093] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:71:0)
[16:36:35.093] Timer tick, asking Buildbucket for the build status
[16:36:35.575] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:35.575] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:72:0) after 1m0s
[16:37:35.644] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:72:0)
[16:37:35.644] Timer tick, asking Buildbucket for the build status
[16:37:35.828] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:35.828] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:73:0) after 1m0s
[16:38:35.856] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:73:0)
[16:38:35.856] Timer tick, asking Buildbucket for the build status
[16:38:36.041] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:36.041] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:74:0) after 1m0s
[16:39:36.019] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:74:0)
[16:39:36.019] Timer tick, asking Buildbucket for the build status
[16:39:36.280] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:36.280] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:75:0) after 1m0s
[16:40:36.297] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:75:0)
[16:40:36.297] Timer tick, asking Buildbucket for the build status
[16:40:36.397] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:36.397] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:76:0) after 1m0s
[16:41:36.543] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:76:0)
[16:41:36.544] Timer tick, asking Buildbucket for the build status
[16:41:36.637] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:36.637] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:77:0) after 1m0s
[16:42:36.704] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:77:0)
[16:42:36.704] Timer tick, asking Buildbucket for the build status
[16:42:36.819] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:36.820] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:78:0) after 1m0s
[16:43:36.980] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:78:0)
[16:43:36.980] Timer tick, asking Buildbucket for the build status
[16:43:37.414] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:37.414] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:79:0) after 1m0s
[16:44:37.556] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:79:0)
[16:44:37.557] Timer tick, asking Buildbucket for the build status
[16:44:37.616] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:37.616] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:80:0) after 1m0s
[16:45:37.642] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:80:0)
[16:45:37.642] Timer tick, asking Buildbucket for the build status
[16:45:37.740] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:37.740] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:81:0) after 1m0s
[16:46:37.795] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:81:0)
[16:46:37.795] Timer tick, asking Buildbucket for the build status
[16:46:38.108] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:38.108] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:82:0) after 1m0s
[16:47:38.136] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:82:0)
[16:47:38.136] Timer tick, asking Buildbucket for the build status
[16:47:38.249] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:38.249] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:83:0) after 1m0s
[16:48:38.287] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:83:0)
[16:48:38.287] Timer tick, asking Buildbucket for the build status
[16:48:38.608] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:38.608] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:84:0) after 1m0s
[16:49:38.670] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:84:0)
[16:49:38.670] Timer tick, asking Buildbucket for the build status
[16:49:38.729] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:38.729] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:85:0) after 1m0s
[16:50:38.794] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:85:0)
[16:50:38.794] Timer tick, asking Buildbucket for the build status
[16:50:38.851] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:38.851] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:86:0) after 1m0s
[16:51:38.935] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:86:0)
[16:51:38.935] Timer tick, asking Buildbucket for the build status
[16:51:39.013] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:39.013] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:87:0) after 1m0s
[16:52:39.008] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:87:0)
[16:52:39.008] Timer tick, asking Buildbucket for the build status
[16:52:39.148] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:39.148] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:88:0) after 1m0s
[16:53:39.196] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:88:0)
[16:53:39.196] Timer tick, asking Buildbucket for the build status
[16:53:39.269] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:39.269] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:89:0) after 1m0s
[16:54:39.306] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:89:0)
[16:54:39.306] Timer tick, asking Buildbucket for the build status
[16:54:39.388] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:39.389] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:90:0) after 1m0s
[16:55:39.409] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:90:0)
[16:55:39.409] Timer tick, asking Buildbucket for the build status
[16:55:39.491] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:39.491] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:91:0) after 1m0s
[16:56:39.512] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:91:0)
[16:56:39.512] Timer tick, asking Buildbucket for the build status
[16:56:39.808] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:39.808] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:92:0) after 1m0s
[16:57:39.830] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:92:0)
[16:57:39.830] Timer tick, asking Buildbucket for the build status
[16:57:39.935] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:39.935] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:93:0) after 1m0s
[16:58:40.018] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:93:0)
[16:58:40.018] Timer tick, asking Buildbucket for the build status
[16:58:40.091] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:40.091] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:94:0) after 1m0s
[16:59:40.153] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:94:0)
[16:59:40.153] Timer tick, asking Buildbucket for the build status
[16:59:40.236] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:40.236] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:95:0) after 1m0s
[17:00:40.326] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:95:0)
[17:00:40.326] Timer tick, asking Buildbucket for the build status
[17:00:40.670] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:40.670] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:96:0) after 1m0s
[17:01:40.773] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:96:0)
[17:01:40.773] Timer tick, asking Buildbucket for the build status
[17:01:40.836] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:40.836] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:97:0) after 1m0s
[17:02:41.010] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:97:0)
[17:02:41.010] Timer tick, asking Buildbucket for the build status
[17:02:41.206] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:41.206] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:98:0) after 1m0s
[17:03:41.215] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:98:0)
[17:03:41.215] Timer tick, asking Buildbucket for the build status
[17:03:41.291] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:41.291] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:99:0) after 1m0s
[17:04:41.432] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:99:0)
[17:04:41.432] Timer tick, asking Buildbucket for the build status
[17:04:41.551] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:41.551] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:100:0) after 1m0s
[17:05:41.669] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:100:0)
[17:05:41.669] Timer tick, asking Buildbucket for the build status
[17:05:41.727] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:41.727] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:101:0) after 1m0s
[17:06:41.753] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:101:0)
[17:06:41.753] Timer tick, asking Buildbucket for the build status
[17:06:42.065] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:42.065] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:102:0) after 1m0s
[17:07:42.076] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:102:0)
[17:07:42.076] Timer tick, asking Buildbucket for the build status
[17:07:42.187] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:42.187] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:103:0) after 1m0s
[17:08:42.208] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:103:0)
[17:08:42.208] Timer tick, asking Buildbucket for the build status
[17:08:42.269] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:42.269] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:104:0) after 1m0s
[17:09:42.355] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:104:0)
[17:09:42.355] Timer tick, asking Buildbucket for the build status
[17:09:42.727] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:42.727] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:105:0) after 1m0s
[17:10:42.806] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:105:0)
[17:10:42.806] Timer tick, asking Buildbucket for the build status
[17:10:42.909] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:42.909] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:106:0) after 1m0s
[17:11:43.042] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:106:0)
[17:11:43.042] Timer tick, asking Buildbucket for the build status
[17:11:43.103] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:43.103] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:107:0) after 1m0s
[17:12:43.221] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:107:0)
[17:12:43.221] Timer tick, asking Buildbucket for the build status
[17:12:43.321] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:43.321] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:108:0) after 1m0s
[17:13:43.319] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:108:0)
[17:13:43.319] Timer tick, asking Buildbucket for the build status
[17:13:43.534] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:43.534] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:109:0) after 1m0s
[17:14:43.529] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:109:0)
[17:14:43.529] Timer tick, asking Buildbucket for the build status
[17:14:43.595] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:43.595] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:110:0) after 1m0s
[17:15:43.626] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:110:0)
[17:15:43.626] Timer tick, asking Buildbucket for the build status
[17:15:43.907] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:43.907] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:111:0) after 1m0s
[17:16:43.933] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:111:0)
[17:16:43.933] Timer tick, asking Buildbucket for the build status
[17:16:44.296] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:44.296] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:112:0) after 1m0s
[17:17:44.318] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:112:0)
[17:17:44.318] Timer tick, asking Buildbucket for the build status
[17:17:44.710] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:44.710] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:113:0) after 1m0s
[17:18:44.840] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:113:0)
[17:18:44.840] Timer tick, asking Buildbucket for the build status
[17:18:44.926] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:44.926] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:114:0) after 1m0s
[17:19:45.060] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:114:0)
[17:19:45.060] Timer tick, asking Buildbucket for the build status
[17:19:45.129] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:45.129] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:115:0) after 1m0s
[17:20:45.212] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:115:0)
[17:20:45.212] Timer tick, asking Buildbucket for the build status
[17:20:45.294] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:45.294] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:116:0) after 1m0s
[17:21:45.384] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:116:0)
[17:21:45.384] Timer tick, asking Buildbucket for the build status
[17:21:45.453] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:45.453] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:117:0) after 1m0s
[17:22:45.522] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:117:0)
[17:22:45.522] Timer tick, asking Buildbucket for the build status
[17:22:45.663] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:45.663] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:118:0) after 1m0s
[17:23:45.938] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:118:0)
[17:23:45.938] Timer tick, asking Buildbucket for the build status
[17:23:46.130] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:46.130] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:119:0) after 1m0s
[17:24:46.153] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:119:0)
[17:24:46.153] Timer tick, asking Buildbucket for the build status
[17:24:46.257] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:46.257] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:120:0) after 1m0s
[17:25:46.362] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:120:0)
[17:25:46.362] Timer tick, asking Buildbucket for the build status
[17:25:46.435] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:46.435] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:121:0) after 1m0s
[17:26:46.466] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:121:0)
[17:26:46.466] Timer tick, asking Buildbucket for the build status
[17:26:46.544] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:46.544] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:122:0) after 1m0s
[17:27:46.586] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:122:0)
[17:27:46.586] Timer tick, asking Buildbucket for the build status
[17:27:46.718] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:46.718] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:123:0) after 1m0s
[17:28:46.873] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:123:0)
[17:28:46.873] Timer tick, asking Buildbucket for the build status
[17:28:47.200] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:47.200] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:124:0) after 1m0s
[17:29:47.231] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:124:0)
[17:29:47.231] Timer tick, asking Buildbucket for the build status
[17:29:47.304] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:47.304] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:125:0) after 1m0s
[17:30:47.407] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:125:0)
[17:30:47.407] Timer tick, asking Buildbucket for the build status
[17:30:47.469] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:47.469] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:126:0) after 1m0s
[17:31:47.632] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:126:0)
[17:31:47.632] Timer tick, asking Buildbucket for the build status
[17:31:47.750] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:47.750] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:127:0) after 1m0s
[17:32:48.017] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:127:0)
[17:32:48.017] Timer tick, asking Buildbucket for the build status
[17:32:48.160] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:48.160] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:128:0) after 1m0s
[17:33:48.209] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:128:0)
[17:33:48.209] Timer tick, asking Buildbucket for the build status
[17:33:48.495] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:48.495] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:129:0) after 1m0s
[17:34:48.561] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:129:0)
[17:34:48.561] Timer tick, asking Buildbucket for the build status
[17:34:48.901] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:48.901] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:130:0) after 1m0s
[17:35:48.966] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:130:0)
[17:35:48.966] Timer tick, asking Buildbucket for the build status
[17:35:49.038] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:49.038] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:131:0) after 1m0s
[17:36:49.200] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:131:0)
[17:36:49.200] Timer tick, asking Buildbucket for the build status
[17:36:49.301] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:49.301] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:132:0) after 1m0s
[17:37:49.392] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:132:0)
[17:37:49.392] Timer tick, asking Buildbucket for the build status
[17:37:49.510] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:49.510] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:133:0) after 1m0s
[17:38:49.555] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:133:0)
[17:38:49.555] Timer tick, asking Buildbucket for the build status
[17:38:49.629] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:49.629] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:134:0) after 1m0s
[17:39:49.716] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:134:0)
[17:39:49.716] Timer tick, asking Buildbucket for the build status
[17:39:49.806] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:49.806] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:135:0) after 1m0s
[17:40:49.849] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:135:0)
[17:40:49.849] Timer tick, asking Buildbucket for the build status
[17:40:49.937] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:49.937] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:136:0) after 1m0s
[17:41:49.991] Handling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:136:0)
[17:41:49.991] Timer tick, asking Buildbucket for the build status
[17:41:50.069] Build 8857483158978006864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:50.069] Scheduling timer "check-buildbucket-build-status" (r8/linux-jdk8:9022913221120766528:137:0) after 1m0s
[17:41:57.464] Received PubSub notification, asking Buildbucket for the build status
[17:41:57.531] Build 8857483158978006864: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[17:41:57.531] Buildbucket build:
{
"build": {
"bucket": "luci.r8.ci",
"canary_preference": "PROD",
"completed_ts": "1611250916749353",
"created_by": "project:r8",
"created_ts": "1611242825489805",
"failure_reason": "BUILD_FAILURE",
"id": "8857483158978006864",
"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\": \"6a1efd59003001a8e02f744cca769b65fde9ef13\"}, \"id\": \"https://r8.googlesource.com/r8/+/refs/heads/master@6a1efd59003001a8e02f744cca769b65fde9ef13\", \"title\": \"6a1efd59003001a8e02f744cca769b65fde9ef13\", \"url\": \"https://r8.googlesource.com/r8/+/6a1efd59003001a8e02f744cca769b65fde9ef13\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://r8.googlesource.com/r8\", \"revision\": \"ad5218afc174624f39b518025522fe36dcffd2b5\"}, \"id\": \"https://r8.googlesource.com/r8/+/refs/heads/master@ad5218afc174624f39b518025522fe36dcffd2b5\", \"title\": \"ad5218afc174624f39b518025522fe36dcffd2b5\", \"url\": \"https://r8.googlesource.com/r8/+/ad5218afc174624f39b518025522fe36dcffd2b5\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://r8.googlesource.com/r8\", \"revision\": \"ad5218afc174624f39b518025522fe36dcffd2b5\"}}",
"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\": \"6a1efd59003001a8e02f744cca769b65fde9ef13\"}, \"id\": \"https://r8.googlesource.com/r8/+/refs/heads/master@6a1efd59003001a8e02f744cca769b65fde9ef13\", \"title\": \"6a1efd59003001a8e02f744cca769b65fde9ef13\", \"url\": \"https://r8.googlesource.com/r8/+/6a1efd59003001a8e02f744cca769b65fde9ef13\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://r8.googlesource.com/r8\", \"revision\": \"ad5218afc174624f39b518025522fe36dcffd2b5\"}, \"id\": \"https://r8.googlesource.com/r8/+/refs/heads/master@ad5218afc174624f39b518025522fe36dcffd2b5\", \"title\": \"ad5218afc174624f39b518025522fe36dcffd2b5\", \"url\": \"https://r8.googlesource.com/r8/+/ad5218afc174624f39b518025522fe36dcffd2b5\"}]}, \"bot_id\": \"luci-r8-custom-ci-xenial-3-sxvn\", \"branch\": \"refs/heads/master\", \"builder_group\": \"internal.client.r8\", \"buildername\": \"linux-jdk8\", \"buildnumber\": 373, \"path_config\": \"generic\", \"recipe\": \"rex\", \"repository\": \"https://r8.googlesource.com/r8\", \"revision\": \"ad5218afc174624f39b518025522fe36dcffd2b5\", \"test_options\": [\"--runtimes=jdk8\", \"--no_internal\", \"--one_line_per_test\", \"--archive_failures\"]}, \"swarming\": {\"bot_dimensions\": {\"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"r8bot-265908\"], \"gpu\": [\"none\"], \"id\": [\"luci-r8-custom-ci-xenial-3-sxvn\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-8\"], \"normal\": [\"true\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.r8.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5671-1d9dea8\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-a\"]}}, \"ui\": {\"info\": \"Step('Run tests') (retcode: 1)\"}}",
"service_account": "r8-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611243130405275",
"status": "COMPLETED",
"status_changed_ts": "1611243130802691",
"tags": [
"build_address:luci.r8.ci/linux-jdk8/373",
"builder:linux-jdk8",
"buildset:commit/git/ad5218afc174624f39b518025522fe36dcffd2b5",
"buildset:commit/gitiles/r8.googlesource.com/r8/+/ad5218afc174624f39b518025522fe36dcffd2b5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022913221120766528",
"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/8857483158978006864/+/annotations",
"swarming_tag:luci_project:r8",
"swarming_tag:os:Linux",
"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:513e5ffa89295a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1611250917060978",
"url": "https://ci.chromium.org/b/8857483158978006864",
"utcnow_ts": "1611250917524258"
}
}
[17:41:57.531] Invocation finished in 2h14m53.469883302s with status FAILED