[08:17:03.540] New invocation is queued and will start shortly
[08:17:04.638] Starting the invocation (attempt 1)
[08:17:04.718] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:17:04.718] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:17:04.718] Buildbucket request:
{
"bucket": "luci.v8.ci",
"client_operation_id": "9021490723486554752",
"parameters_json": "{\"builder_name\":\"V8 Mac64 - debug\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/v8/v8\",\"revision\":\"a6e01fb95b7c6001f8cf4fbb015eab5b1a30564f\"},\"id\":\"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@a6e01fb95b7c6001f8cf4fbb015eab5b1a30564f\",\"title\":\"a6e01fb95b7c6001f8cf4fbb015eab5b1a30564f\",\"url\":\"https://chromium.googlesource.com/v8/v8/+/a6e01fb95b7c6001f8cf4fbb015eab5b1a30564f\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/v8/v8\",\"revision\":\"a6e01fb95b7c6001f8cf4fbb015eab5b1a30564f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:V8 Mac64 - debug",
"scheduler_invocation_id:9021490723486554752",
"scheduler_job_id:v8/ci-V8 Mac64 - debug",
"user_agent:luci-scheduler",
"buildset:commit/git/a6e01fb95b7c6001f8cf4fbb015eab5b1a30564f",
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/a6e01fb95b7c6001f8cf4fbb015eab5b1a30564f",
"gitiles_ref:refs/heads/master"
]
}
[08:17:05.486] Buildbucket response:
{
"build": {
"bucket": "luci.v8.ci",
"canary_preference": "PROD",
"created_by": "project:v8",
"created_ts": "1612599424840668",
"id": "8856060661457327200",
"parameters_json": "{\"builder_name\": \"V8 Mac64 - debug\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"a6e01fb95b7c6001f8cf4fbb015eab5b1a30564f\"}, \"id\": \"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@a6e01fb95b7c6001f8cf4fbb015eab5b1a30564f\", \"title\": \"a6e01fb95b7c6001f8cf4fbb015eab5b1a30564f\", \"url\": \"https://chromium.googlesource.com/v8/v8/+/a6e01fb95b7c6001f8cf4fbb015eab5b1a30564f\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"a6e01fb95b7c6001f8cf4fbb015eab5b1a30564f\"}}",
"project": "v8",
"result_details_json": "{\"properties\": {}}",
"service_account": "v8-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612599425324477",
"tags": [
"build_address:luci.v8.ci/V8 Mac64 - debug/32699",
"builder:V8 Mac64 - debug",
"buildset:commit/git/a6e01fb95b7c6001f8cf4fbb015eab5b1a30564f",
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/a6e01fb95b7c6001f8cf4fbb015eab5b1a30564f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021490723486554752",
"scheduler_job_id:v8/ci-V8 Mac64 - debug",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/v8/buildbucket/cr-buildbucket.appspot.com/8856060661457327200/+/annotations",
"swarming_tag:luci_project:v8",
"swarming_tag:recipe_name:v8",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1612599425324590",
"url": "https://ci.chromium.org/b/8856060661457327200",
"utcnow_ts": "1612599425479544"
}
}
[08:17:05.486] Task URL: https://ci.chromium.org/b/8856060661457327200
[08:17:05.486] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:2:0) after 1m0s
[08:18:05.908] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:2:0)
[08:18:05.908] Timer tick, asking Buildbucket for the build status
[08:18:06.085] Build 8856060661457327200: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:18:06.085] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:3:0) after 1m0s
[08:19:06.343] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:3:0)
[08:19:06.343] Timer tick, asking Buildbucket for the build status
[08:19:06.432] Build 8856060661457327200: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:19:06.432] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:4:0) after 1m0s
[08:20:06.573] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:4:0)
[08:20:06.573] Timer tick, asking Buildbucket for the build status
[08:20:07.528] Build 8856060661457327200: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:20:07.528] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:5:0) after 1m0s
[08:21:07.575] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:5:0)
[08:21:07.575] Timer tick, asking Buildbucket for the build status
[08:21:09.179] Build 8856060661457327200: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:21:09.179] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:6:0) after 1m0s
[08:22:09.329] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:6:0)
[08:22:09.329] Timer tick, asking Buildbucket for the build status
[08:22:09.759] Build 8856060661457327200: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:22:09.759] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:7:0) after 1m0s
[08:22:10.862] Received PubSub notification, asking Buildbucket for the build status
[08:22:11.250] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:09.794] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:7:0)
[08:23:09.794] Timer tick, asking Buildbucket for the build status
[08:23:09.899] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:09.899] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:9:0) after 1m0s
[08:24:09.948] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:9:0)
[08:24:09.948] Timer tick, asking Buildbucket for the build status
[08:24:10.036] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:10.036] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:10:0) after 1m0s
[08:25:10.043] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:10:0)
[08:25:10.043] Timer tick, asking Buildbucket for the build status
[08:25:10.143] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:10.143] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:11:0) after 1m0s
[08:26:10.166] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:11:0)
[08:26:10.166] Timer tick, asking Buildbucket for the build status
[08:26:11.539] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:11.539] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:12:0) after 1m0s
[08:27:11.582] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:12:0)
[08:27:11.582] Timer tick, asking Buildbucket for the build status
[08:27:11.871] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:11.871] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:13:0) after 1m0s
[08:28:11.872] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:13:0)
[08:28:11.872] Timer tick, asking Buildbucket for the build status
[08:28:12.055] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:12.055] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:14:0) after 1m0s
[08:29:12.108] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:14:0)
[08:29:12.108] Timer tick, asking Buildbucket for the build status
[08:29:12.768] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:12.768] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:15:0) after 1m0s
[08:30:12.791] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:15:0)
[08:30:12.791] Timer tick, asking Buildbucket for the build status
[08:30:12.895] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:12.896] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:16:0) after 1m0s
[08:31:12.965] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:16:0)
[08:31:12.965] Timer tick, asking Buildbucket for the build status
[08:31:13.064] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:13.064] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:17:0) after 1m0s
[08:32:13.223] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:17:0)
[08:32:13.223] Timer tick, asking Buildbucket for the build status
[08:32:13.799] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:13.799] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:18:0) after 1m0s
[08:33:13.964] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:18:0)
[08:33:13.964] Timer tick, asking Buildbucket for the build status
[08:33:15.316] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:15.316] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:19:0) after 1m0s
[08:34:15.482] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:19:0)
[08:34:15.482] Timer tick, asking Buildbucket for the build status
[08:34:15.542] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:15.542] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:20:0) after 1m0s
[08:35:15.804] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:20:0)
[08:35:15.804] Timer tick, asking Buildbucket for the build status
[08:35:16.301] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:16.301] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:21:0) after 1m0s
[08:36:16.297] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:21:0)
[08:36:16.297] Timer tick, asking Buildbucket for the build status
[08:36:16.368] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:16.368] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:22:0) after 1m0s
[08:37:16.388] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:22:0)
[08:37:16.388] Timer tick, asking Buildbucket for the build status
[08:37:16.548] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:16.548] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:23:0) after 1m0s
[08:38:16.556] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:23:0)
[08:38:16.556] Timer tick, asking Buildbucket for the build status
[08:38:16.800] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:16.800] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:24:0) after 1m0s
[08:39:16.808] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:24:0)
[08:39:16.808] Timer tick, asking Buildbucket for the build status
[08:39:17.064] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:17.064] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:25:0) after 1m0s
[08:40:17.094] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:25:0)
[08:40:17.094] Timer tick, asking Buildbucket for the build status
[08:40:17.194] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:17.194] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:26:0) after 1m0s
[08:41:17.273] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:26:0)
[08:41:17.273] Timer tick, asking Buildbucket for the build status
[08:41:17.784] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:17.784] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:27:0) after 1m0s
[08:42:17.822] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:27:0)
[08:42:17.822] Timer tick, asking Buildbucket for the build status
[08:42:17.928] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:17.928] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:28:0) after 1m0s
[08:43:17.981] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:28:0)
[08:43:17.981] Timer tick, asking Buildbucket for the build status
[08:43:18.042] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:18.042] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:29:0) after 1m0s
[08:44:18.069] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:29:0)
[08:44:18.069] Timer tick, asking Buildbucket for the build status
[08:44:18.125] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:18.125] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:30:0) after 1m0s
[08:45:18.117] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:30:0)
[08:45:18.117] Timer tick, asking Buildbucket for the build status
[08:45:18.213] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:18.213] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:31:0) after 1m0s
[08:46:18.249] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:31:0)
[08:46:18.249] Timer tick, asking Buildbucket for the build status
[08:46:18.528] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:18.528] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:32:0) after 1m0s
[08:47:18.560] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:32:0)
[08:47:18.560] Timer tick, asking Buildbucket for the build status
[08:47:18.701] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:18.701] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:33:0) after 1m0s
[08:48:18.701] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:33:0)
[08:48:18.701] Timer tick, asking Buildbucket for the build status
[08:48:18.766] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:18.766] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:34:0) after 1m0s
[08:49:18.786] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:34:0)
[08:49:18.786] Timer tick, asking Buildbucket for the build status
[08:49:18.855] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:18.855] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:35:0) after 1m0s
[08:50:18.960] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:35:0)
[08:50:18.960] Timer tick, asking Buildbucket for the build status
[08:50:19.350] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:19.350] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:36:0) after 1m0s
[08:51:19.450] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:36:0)
[08:51:19.450] Timer tick, asking Buildbucket for the build status
[08:51:19.514] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:19.514] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:37:0) after 1m0s
[08:52:19.613] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:37:0)
[08:52:19.613] Timer tick, asking Buildbucket for the build status
[08:52:19.930] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:19.930] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:38:0) after 1m0s
[08:53:19.930] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:38:0)
[08:53:19.930] Timer tick, asking Buildbucket for the build status
[08:53:20.063] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:20.063] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:39:0) after 1m0s
[08:54:20.161] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:39:0)
[08:54:20.161] Timer tick, asking Buildbucket for the build status
[08:54:20.223] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:20.223] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:40:0) after 1m0s
[08:55:20.245] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:40:0)
[08:55:20.245] Timer tick, asking Buildbucket for the build status
[08:55:20.611] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:20.611] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:41:0) after 1m0s
[08:56:20.667] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:41:0)
[08:56:20.667] Timer tick, asking Buildbucket for the build status
[08:56:20.738] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:20.738] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:42:0) after 1m0s
[08:57:20.790] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:42:0)
[08:57:20.790] Timer tick, asking Buildbucket for the build status
[08:57:21.191] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:21.191] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:43:0) after 1m0s
[08:58:21.211] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:43:0)
[08:58:21.211] Timer tick, asking Buildbucket for the build status
[08:58:21.291] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:21.291] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:44:0) after 1m0s
[08:59:21.310] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:44:0)
[08:59:21.310] Timer tick, asking Buildbucket for the build status
[08:59:21.509] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:21.509] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:45:0) after 1m0s
[09:00:21.565] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:45:0)
[09:00:21.565] Timer tick, asking Buildbucket for the build status
[09:00:21.622] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:21.622] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:46:0) after 1m0s
[09:01:21.640] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:46:0)
[09:01:21.640] Timer tick, asking Buildbucket for the build status
[09:01:21.730] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:21.730] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:47:0) after 1m0s
[09:02:21.757] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:47:0)
[09:02:21.757] Timer tick, asking Buildbucket for the build status
[09:02:22.331] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:22.331] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:48:0) after 1m0s
[09:03:22.349] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:48:0)
[09:03:22.349] Timer tick, asking Buildbucket for the build status
[09:03:22.634] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:22.634] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:49:0) after 1m0s
[09:04:22.651] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:49:0)
[09:04:22.651] Timer tick, asking Buildbucket for the build status
[09:04:22.896] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:22.896] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:50:0) after 1m0s
[09:05:22.953] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:50:0)
[09:05:22.953] Timer tick, asking Buildbucket for the build status
[09:05:23.207] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:23.207] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:51:0) after 1m0s
[09:06:23.292] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:51:0)
[09:06:23.292] Timer tick, asking Buildbucket for the build status
[09:06:23.386] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:23.386] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:52:0) after 1m0s
[09:07:23.403] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:52:0)
[09:07:23.403] Timer tick, asking Buildbucket for the build status
[09:07:23.844] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:23.844] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:53:0) after 1m0s
[09:08:23.957] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:53:0)
[09:08:23.957] Timer tick, asking Buildbucket for the build status
[09:08:24.208] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:24.208] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:54:0) after 1m0s
[09:09:24.259] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:54:0)
[09:09:24.259] Timer tick, asking Buildbucket for the build status
[09:09:24.318] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:24.318] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:55:0) after 1m0s
[09:10:24.447] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:55:0)
[09:10:24.447] Timer tick, asking Buildbucket for the build status
[09:10:24.594] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:24.594] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:56:0) after 1m0s
[09:11:24.641] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:56:0)
[09:11:24.641] Timer tick, asking Buildbucket for the build status
[09:11:24.843] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:24.843] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:57:0) after 1m0s
[09:12:24.917] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:57:0)
[09:12:24.917] Timer tick, asking Buildbucket for the build status
[09:12:25.145] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:25.145] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:58:0) after 1m0s
[09:13:25.372] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:58:0)
[09:13:25.372] Timer tick, asking Buildbucket for the build status
[09:13:25.535] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:25.535] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:59:0) after 1m0s
[09:14:25.600] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:59:0)
[09:14:25.600] Timer tick, asking Buildbucket for the build status
[09:14:26.803] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:26.803] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:60:0) after 1m0s
[09:15:26.824] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:60:0)
[09:15:26.824] Timer tick, asking Buildbucket for the build status
[09:15:26.887] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:26.887] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:61:0) after 1m0s
[09:16:26.915] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:61:0)
[09:16:26.915] Timer tick, asking Buildbucket for the build status
[09:16:27.002] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:27.002] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:62:0) after 1m0s
[09:17:27.024] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:62:0)
[09:17:27.024] Timer tick, asking Buildbucket for the build status
[09:17:27.186] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:27.186] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:63:0) after 1m0s
[09:18:27.220] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:63:0)
[09:18:27.220] Timer tick, asking Buildbucket for the build status
[09:18:27.293] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:27.293] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:64:0) after 1m0s
[09:19:27.367] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:64:0)
[09:19:27.367] Timer tick, asking Buildbucket for the build status
[09:19:27.500] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:27.500] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:65:0) after 1m0s
[09:20:27.567] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:65:0)
[09:20:27.567] Timer tick, asking Buildbucket for the build status
[09:20:27.691] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:27.691] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:66:0) after 1m0s
[09:21:27.751] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:66:0)
[09:21:27.751] Timer tick, asking Buildbucket for the build status
[09:21:27.806] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:27.806] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:67:0) after 1m0s
[09:22:27.821] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:67:0)
[09:22:27.821] Timer tick, asking Buildbucket for the build status
[09:22:27.908] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:27.908] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:68:0) after 1m0s
[09:23:27.939] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:68:0)
[09:23:27.939] Timer tick, asking Buildbucket for the build status
[09:23:27.987] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:27.987] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:69:0) after 1m0s
[09:24:27.993] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:69:0)
[09:24:27.993] Timer tick, asking Buildbucket for the build status
[09:24:28.250] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:28.250] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:70:0) after 1m0s
[09:25:28.226] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:70:0)
[09:25:28.226] Timer tick, asking Buildbucket for the build status
[09:25:28.282] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:28.282] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:71:0) after 1m0s
[09:26:28.308] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:71:0)
[09:26:28.308] Timer tick, asking Buildbucket for the build status
[09:26:28.393] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:28.393] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:72:0) after 1m0s
[09:27:28.435] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:72:0)
[09:27:28.435] Timer tick, asking Buildbucket for the build status
[09:27:28.564] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:28.564] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:73:0) after 1m0s
[09:28:28.682] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:73:0)
[09:28:28.682] Timer tick, asking Buildbucket for the build status
[09:28:28.748] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:28.748] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:74:0) after 1m0s
[09:29:28.768] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:74:0)
[09:29:28.768] Timer tick, asking Buildbucket for the build status
[09:29:29.063] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:29.063] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:75:0) after 1m0s
[09:30:29.150] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:75:0)
[09:30:29.150] Timer tick, asking Buildbucket for the build status
[09:30:29.233] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:29.233] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:76:0) after 1m0s
[09:31:29.253] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:76:0)
[09:31:29.253] Timer tick, asking Buildbucket for the build status
[09:31:29.325] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:29.325] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:77:0) after 1m0s
[09:32:29.299] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:77:0)
[09:32:29.299] Timer tick, asking Buildbucket for the build status
[09:32:29.642] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:29.642] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:78:0) after 1m0s
[09:33:29.666] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:78:0)
[09:33:29.666] Timer tick, asking Buildbucket for the build status
[09:33:29.732] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:29.732] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:79:0) after 1m0s
[09:34:29.735] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:79:0)
[09:34:29.735] Timer tick, asking Buildbucket for the build status
[09:34:29.797] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:29.797] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:80:0) after 1m0s
[09:35:29.850] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:80:0)
[09:35:29.850] Timer tick, asking Buildbucket for the build status
[09:35:29.917] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:29.917] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:81:0) after 1m0s
[09:36:30.368] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:81:0)
[09:36:30.368] Timer tick, asking Buildbucket for the build status
[09:36:30.844] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:30.844] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:82:0) after 1m0s
[09:37:30.921] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:82:0)
[09:37:30.921] Timer tick, asking Buildbucket for the build status
[09:37:31.048] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:31.048] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:83:0) after 1m0s
[09:38:31.188] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:83:0)
[09:38:31.188] Timer tick, asking Buildbucket for the build status
[09:38:31.531] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:31.531] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:84:0) after 1m0s
[09:39:31.676] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:84:0)
[09:39:31.676] Timer tick, asking Buildbucket for the build status
[09:39:32.302] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:32.302] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:85:0) after 1m0s
[09:40:32.323] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:85:0)
[09:40:32.323] Timer tick, asking Buildbucket for the build status
[09:40:32.544] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:32.544] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:86:0) after 1m0s
[09:41:32.565] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:86:0)
[09:41:32.565] Timer tick, asking Buildbucket for the build status
[09:41:32.647] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:32.647] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:87:0) after 1m0s
[09:42:32.668] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:87:0)
[09:42:32.668] Timer tick, asking Buildbucket for the build status
[09:42:32.785] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:32.785] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:88:0) after 1m0s
[09:43:32.907] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:88:0)
[09:43:32.907] Timer tick, asking Buildbucket for the build status
[09:43:33.024] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:33.024] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:89:0) after 1m0s
[09:44:33.044] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:89:0)
[09:44:33.044] Timer tick, asking Buildbucket for the build status
[09:44:33.114] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:33.114] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:90:0) after 1m0s
[09:45:33.092] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:90:0)
[09:45:33.092] Timer tick, asking Buildbucket for the build status
[09:45:33.303] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:33.303] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:91:0) after 1m0s
[09:46:33.407] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:91:0)
[09:46:33.407] Timer tick, asking Buildbucket for the build status
[09:46:33.633] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:33.633] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:92:0) after 1m0s
[09:47:33.652] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:92:0)
[09:47:33.652] Timer tick, asking Buildbucket for the build status
[09:47:33.738] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:33.738] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:93:0) after 1m0s
[09:48:33.889] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:93:0)
[09:48:33.889] Timer tick, asking Buildbucket for the build status
[09:48:34.006] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:34.006] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:94:0) after 1m0s
[09:49:34.098] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:94:0)
[09:49:34.098] Timer tick, asking Buildbucket for the build status
[09:49:34.271] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:34.271] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:95:0) after 1m0s
[09:50:34.290] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:95:0)
[09:50:34.290] Timer tick, asking Buildbucket for the build status
[09:50:34.549] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:34.549] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:96:0) after 1m0s
[09:51:34.643] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:96:0)
[09:51:34.643] Timer tick, asking Buildbucket for the build status
[09:51:34.700] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:34.700] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:97:0) after 1m0s
[09:52:34.831] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:97:0)
[09:52:34.831] Timer tick, asking Buildbucket for the build status
[09:52:35.010] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:35.010] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:98:0) after 1m0s
[09:53:35.042] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:98:0)
[09:53:35.042] Timer tick, asking Buildbucket for the build status
[09:53:35.172] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:35.172] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:99:0) after 1m0s
[09:54:35.193] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:99:0)
[09:54:35.193] Timer tick, asking Buildbucket for the build status
[09:54:35.253] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:35.253] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:100:0) after 1m0s
[09:55:35.284] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:100:0)
[09:55:35.284] Timer tick, asking Buildbucket for the build status
[09:55:35.389] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:35.389] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:101:0) after 1m0s
[09:56:35.476] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:101:0)
[09:56:35.476] Timer tick, asking Buildbucket for the build status
[09:56:35.539] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:35.539] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:102:0) after 1m0s
[09:57:35.693] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:102:0)
[09:57:35.693] Timer tick, asking Buildbucket for the build status
[09:57:36.098] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:36.098] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:103:0) after 1m0s
[09:58:36.122] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:103:0)
[09:58:36.122] Timer tick, asking Buildbucket for the build status
[09:58:36.251] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:36.251] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:104:0) after 1m0s
[09:59:36.357] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:104:0)
[09:59:36.357] Timer tick, asking Buildbucket for the build status
[09:59:36.756] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:36.756] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:105:0) after 1m0s
[10:00:36.908] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:105:0)
[10:00:36.908] Timer tick, asking Buildbucket for the build status
[10:00:37.103] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:37.103] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:106:0) after 1m0s
[10:01:37.105] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:106:0)
[10:01:37.105] Timer tick, asking Buildbucket for the build status
[10:01:37.183] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:37.183] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:107:0) after 1m0s
[10:02:37.343] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:107:0)
[10:02:37.343] Timer tick, asking Buildbucket for the build status
[10:02:37.442] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:37.442] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:108:0) after 1m0s
[10:03:37.653] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:108:0)
[10:03:37.653] Timer tick, asking Buildbucket for the build status
[10:03:37.932] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:37.932] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:109:0) after 1m0s
[10:04:38.001] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:109:0)
[10:04:38.001] Timer tick, asking Buildbucket for the build status
[10:04:38.069] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:38.069] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:110:0) after 1m0s
[10:05:38.185] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:110:0)
[10:05:38.185] Timer tick, asking Buildbucket for the build status
[10:05:38.267] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:38.267] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:111:0) after 1m0s
[10:06:38.415] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:111:0)
[10:06:38.415] Timer tick, asking Buildbucket for the build status
[10:06:38.474] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:38.474] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:112:0) after 1m0s
[10:07:38.451] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:112:0)
[10:07:38.451] Timer tick, asking Buildbucket for the build status
[10:07:38.795] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:38.795] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:113:0) after 1m0s
[10:08:38.872] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:113:0)
[10:08:38.872] Timer tick, asking Buildbucket for the build status
[10:08:38.983] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:38.983] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:114:0) after 1m0s
[10:09:39.008] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:114:0)
[10:09:39.008] Timer tick, asking Buildbucket for the build status
[10:09:39.270] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:39.270] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:115:0) after 1m0s
[10:10:39.305] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:115:0)
[10:10:39.305] Timer tick, asking Buildbucket for the build status
[10:10:39.356] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:39.356] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:116:0) after 1m0s
[10:11:39.356] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:116:0)
[10:11:39.356] Timer tick, asking Buildbucket for the build status
[10:11:39.427] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:39.427] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:117:0) after 1m0s
[10:12:39.454] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:117:0)
[10:12:39.454] Timer tick, asking Buildbucket for the build status
[10:12:39.560] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:39.560] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:118:0) after 1m0s
[10:13:39.590] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:118:0)
[10:13:39.590] Timer tick, asking Buildbucket for the build status
[10:13:39.667] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:39.667] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:119:0) after 1m0s
[10:14:39.696] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:119:0)
[10:14:39.696] Timer tick, asking Buildbucket for the build status
[10:14:39.849] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:39.849] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:120:0) after 1m0s
[10:15:39.882] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:120:0)
[10:15:39.882] Timer tick, asking Buildbucket for the build status
[10:15:39.932] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:39.932] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:121:0) after 1m0s
[10:16:40.025] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:121:0)
[10:16:40.025] Timer tick, asking Buildbucket for the build status
[10:16:40.217] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:40.218] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:122:0) after 1m0s
[10:17:40.240] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:122:0)
[10:17:40.240] Timer tick, asking Buildbucket for the build status
[10:17:40.364] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:40.364] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:123:0) after 1m0s
[10:18:40.438] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:123:0)
[10:18:40.438] Timer tick, asking Buildbucket for the build status
[10:18:40.714] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:40.714] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:124:0) after 1m0s
[10:19:40.803] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:124:0)
[10:19:40.803] Timer tick, asking Buildbucket for the build status
[10:19:40.860] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:40.860] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:125:0) after 1m0s
[10:20:40.893] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:125:0)
[10:20:40.893] Timer tick, asking Buildbucket for the build status
[10:20:41.140] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:41.140] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:126:0) after 1m0s
[10:21:41.192] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:126:0)
[10:21:41.192] Timer tick, asking Buildbucket for the build status
[10:21:41.297] Build 8856060661457327200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:41.297] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Mac64 - debug:9021490723486554752:127:0) after 1m0s
[10:22:15.452] Received PubSub notification, asking Buildbucket for the build status
[10:22:15.605] Build 8856060661457327200: status "COMPLETED", result "FAILURE", failure_reason "INFRA_FAILURE", cancelation_reason ""
[10:22:15.605] Buildbucket build:
{
"build": {
"bucket": "luci.v8.ci",
"canary_preference": "PROD",
"completed_ts": "1612606930942551",
"created_by": "project:v8",
"created_ts": "1612599424840668",
"failure_reason": "INFRA_FAILURE",
"id": "8856060661457327200",
"parameters_json": "{\"builder_name\": \"V8 Mac64 - debug\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"a6e01fb95b7c6001f8cf4fbb015eab5b1a30564f\"}, \"id\": \"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@a6e01fb95b7c6001f8cf4fbb015eab5b1a30564f\", \"title\": \"a6e01fb95b7c6001f8cf4fbb015eab5b1a30564f\", \"url\": \"https://chromium.googlesource.com/v8/v8/+/a6e01fb95b7c6001f8cf4fbb015eab5b1a30564f\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"a6e01fb95b7c6001f8cf4fbb015eab5b1a30564f\"}}",
"project": "v8",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"recipe infra failure: Recipe timed out\"}, \"properties\": {\"$build/goma\": {\"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com/\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/opt/s/w/ir/cache\", \"temp_dir\": \"/opt/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://chromium.googlesource.com/v8/v8\", \"revision\": \"a6e01fb95b7c6001f8cf4fbb015eab5b1a30564f\"}, \"id\": \"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@a6e01fb95b7c6001f8cf4fbb015eab5b1a30564f\", \"title\": \"a6e01fb95b7c6001f8cf4fbb015eab5b1a30564f\", \"url\": \"https://chromium.googlesource.com/v8/v8/+/a6e01fb95b7c6001f8cf4fbb015eab5b1a30564f\"}]}, \"bot_id\": \"build167-m9\", \"branch\": \"refs/heads/master\", \"builder_group\": \"client.v8\", \"buildername\": \"V8 Mac64 - debug\", \"buildnumber\": 32699, \"path_config\": \"generic\", \"recipe\": \"v8\", \"repository\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"a6e01fb95b7c6001f8cf4fbb015eab5b1a30564f\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_11e6e82664cdff9f3e648f766f6d5cc3d58bef8aaf78f7fadeb7f309ade7fc0f_v2\", \"git\", \"goma_v2\", \"osx_sdk\", \"vpython\"], \"cores\": [\"4\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-i7-4578U\"], \"gce\": [\"0\"], \"gpu\": [\"8086\", \"8086:0a2e\"], \"hidpi\": [\"0\"], \"host_class\": [\"default\"], \"id\": [\"build167-m9\"], \"mac_model\": [\"Macmini7,1\"], \"machine_type\": [\"n1-standard-4\"], \"os\": [\"Mac\", \"Mac-10\", \"Mac-10.15\", \"Mac-10.15.6\", \"Mac-10.15.6-19G2021\"], \"pool\": [\"luci.v8.ci\"], \"python\": [\"2.7.16\"], \"server_version\": [\"5676-c76598c\"], \"ssd\": [\"1\"], \"zone\": [\"us\", \"us-atl\", \"us-atl-golo\", \"us-atl-golo-m9\"]}}, \"ui\": {\"info\": \"recipe infra failure: Recipe timed out\"}}",
"service_account": "v8-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1612599728612658",
"status": "COMPLETED",
"status_changed_ts": "1612599729366786",
"tags": [
"build_address:luci.v8.ci/V8 Mac64 - debug/32699",
"builder:V8 Mac64 - debug",
"buildset:commit/git/a6e01fb95b7c6001f8cf4fbb015eab5b1a30564f",
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/a6e01fb95b7c6001f8cf4fbb015eab5b1a30564f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021490723486554752",
"scheduler_job_id:v8/ci-V8 Mac64 - debug",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/v8/buildbucket/cr-buildbucket.appspot.com/8856060661457327200/+/annotations",
"swarming_tag:luci_project:v8",
"swarming_tag:os:Mac",
"swarming_tag:recipe_name:v8",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:518f3c07bd5b4610",
"user_agent:luci-scheduler"
],
"updated_ts": "1612606931237765",
"url": "https://ci.chromium.org/b/8856060661457327200",
"utcnow_ts": "1612606935595027"
}
}
[10:22:15.605] Invocation finished in 2h5m12.083241832s with status FAILED