[06:50:52.453] New invocation is queued and will start shortly
[06:50:53.579] Starting the invocation (attempt 1)
[06:50:53.633] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:50:53.633] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:50:53.633] Buildbucket request:
{
"bucket": "luci.v8.try",
"client_operation_id": "9085457604039315072",
"parameters_json": "{\"builder_name\":\"v8_verify_flakes\",\"properties\":{}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:v8_verify_flakes",
"scheduler_invocation_id:9085457604039315072",
"scheduler_job_id:v8/v8_verify_flakes",
"user_agent:luci-scheduler"
]
}
[06:50:54.327] Buildbucket response:
{
"build": {
"bucket": "luci.v8.try",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1551595853766337",
"id": "8920027541999964544",
"lease_expiration_ts": "1551720054154223",
"lease_key": "1524550268",
"parameters_json": "{\"builder_name\": \"v8_verify_flakes\", \"properties\": {}}",
"project": "v8",
"result_details_json": "{\"properties\": {}}",
"service_account": "v8-try-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1551595854154752",
"tags": [
"builder:v8_verify_flakes",
"scheduler_invocation_id:9085457604039315072",
"scheduler_job_id:v8/v8_verify_flakes",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/v8/buildbucket/cr-buildbucket.appspot.com/8920027541999964544/+/annotations",
"swarming_tag:luci_project:v8",
"swarming_tag:recipe_name:v8/verify_flakes",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:435b2360b6e6ff10",
"user_agent:luci-scheduler"
],
"updated_ts": "1551595854154825",
"url": "https://ci.chromium.org/b/8920027541999964544",
"utcnow_ts": "1551595854317349"
}
}
[06:50:54.328] Task URL: https://ci.chromium.org/b/8920027541999964544
[06:50:54.328] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:2:0) after 1m0s
[06:50:57.645] Received PubSub notification, asking Buildbucket for the build status
[06:50:57.701] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:54.312] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:2:0)
[06:51:54.312] Timer tick, asking Buildbucket for the build status
[06:51:54.423] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:54.423] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:4:0) after 1m0s
[06:52:54.395] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:4:0)
[06:52:54.395] Timer tick, asking Buildbucket for the build status
[06:52:54.575] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:54.575] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:5:0) after 1m0s
[06:53:54.593] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:5:0)
[06:53:54.593] Timer tick, asking Buildbucket for the build status
[06:53:54.892] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:54.892] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:6:0) after 1m0s
[06:54:54.911] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:6:0)
[06:54:54.911] Timer tick, asking Buildbucket for the build status
[06:54:55.484] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:55.484] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:7:0) after 1m0s
[06:55:55.499] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:7:0)
[06:55:55.499] Timer tick, asking Buildbucket for the build status
[06:55:55.580] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:55.580] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:8:0) after 1m0s
[06:56:55.602] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:8:0)
[06:56:55.602] Timer tick, asking Buildbucket for the build status
[06:56:55.708] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:55.708] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:9:0) after 1m0s
[06:57:55.729] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:9:0)
[06:57:55.729] Timer tick, asking Buildbucket for the build status
[06:57:55.804] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:55.804] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:10:0) after 1m0s
[06:58:55.825] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:10:0)
[06:58:55.825] Timer tick, asking Buildbucket for the build status
[06:58:55.912] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:55.912] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:11:0) after 1m0s
[06:59:55.925] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:11:0)
[06:59:55.925] Timer tick, asking Buildbucket for the build status
[06:59:56.026] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:56.026] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:12:0) after 1m0s
[07:00:56.012] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:12:0)
[07:00:56.012] Timer tick, asking Buildbucket for the build status
[07:00:56.109] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:56.109] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:13:0) after 1m0s
[07:01:56.083] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:13:0)
[07:01:56.083] Timer tick, asking Buildbucket for the build status
[07:01:56.156] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:56.156] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:14:0) after 1m0s
[07:02:56.146] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:14:0)
[07:02:56.146] Timer tick, asking Buildbucket for the build status
[07:02:56.228] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:56.228] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:15:0) after 1m0s
[07:03:56.254] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:15:0)
[07:03:56.254] Timer tick, asking Buildbucket for the build status
[07:03:56.367] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:56.367] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:16:0) after 1m0s
[07:04:56.367] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:16:0)
[07:04:56.367] Timer tick, asking Buildbucket for the build status
[07:04:56.644] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:56.644] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:17:0) after 1m0s
[07:05:56.685] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:17:0)
[07:05:56.685] Timer tick, asking Buildbucket for the build status
[07:05:56.895] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:56.895] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:18:0) after 1m0s
[07:06:56.902] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:18:0)
[07:06:56.902] Timer tick, asking Buildbucket for the build status
[07:06:57.085] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:57.085] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:19:0) after 1m0s
[07:07:57.090] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:19:0)
[07:07:57.090] Timer tick, asking Buildbucket for the build status
[07:07:57.358] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:57.358] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:20:0) after 1m0s
[07:08:57.388] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:20:0)
[07:08:57.388] Timer tick, asking Buildbucket for the build status
[07:08:57.492] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:57.492] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:21:0) after 1m0s
[07:09:57.513] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:21:0)
[07:09:57.513] Timer tick, asking Buildbucket for the build status
[07:09:57.623] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:57.623] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:22:0) after 1m0s
[07:10:57.642] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:22:0)
[07:10:57.643] Timer tick, asking Buildbucket for the build status
[07:10:57.907] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:57.907] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:23:0) after 1m0s
[07:11:57.930] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:23:0)
[07:11:57.930] Timer tick, asking Buildbucket for the build status
[07:11:58.087] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:58.087] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:24:0) after 1m0s
[07:12:58.130] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:24:0)
[07:12:58.130] Timer tick, asking Buildbucket for the build status
[07:12:58.458] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:58.458] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:25:0) after 1m0s
[07:13:58.476] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:25:0)
[07:13:58.476] Timer tick, asking Buildbucket for the build status
[07:13:58.558] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:58.558] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:26:0) after 1m0s
[07:14:58.559] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:26:0)
[07:14:58.559] Timer tick, asking Buildbucket for the build status
[07:14:58.784] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:58.784] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:27:0) after 1m0s
[07:15:58.870] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:27:0)
[07:15:58.870] Timer tick, asking Buildbucket for the build status
[07:15:58.938] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:58.939] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:28:0) after 1m0s
[07:16:58.956] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:28:0)
[07:16:58.956] Timer tick, asking Buildbucket for the build status
[07:16:59.046] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:59.046] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:29:0) after 1m0s
[07:17:59.062] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:29:0)
[07:17:59.062] Timer tick, asking Buildbucket for the build status
[07:17:59.138] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:59.138] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:30:0) after 1m0s
[07:18:59.168] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:30:0)
[07:18:59.168] Timer tick, asking Buildbucket for the build status
[07:18:59.259] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:59.259] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:31:0) after 1m0s
[07:19:59.236] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:31:0)
[07:19:59.236] Timer tick, asking Buildbucket for the build status
[07:19:59.311] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:59.311] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:32:0) after 1m0s
[07:20:59.296] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:32:0)
[07:20:59.297] Timer tick, asking Buildbucket for the build status
[07:20:59.579] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:59.579] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:33:0) after 1m0s
[07:21:59.559] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:33:0)
[07:21:59.559] Timer tick, asking Buildbucket for the build status
[07:21:59.735] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:59.735] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:34:0) after 1m0s
[07:22:59.746] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:34:0)
[07:22:59.746] Timer tick, asking Buildbucket for the build status
[07:22:59.821] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:59.821] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:35:0) after 1m0s
[07:23:59.822] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:35:0)
[07:23:59.822] Timer tick, asking Buildbucket for the build status
[07:23:59.897] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:59.897] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:36:0) after 1m0s
[07:24:59.924] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:36:0)
[07:24:59.924] Timer tick, asking Buildbucket for the build status
[07:25:00.373] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:00.373] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:37:0) after 1m0s
[07:26:00.408] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:37:0)
[07:26:00.408] Timer tick, asking Buildbucket for the build status
[07:26:00.479] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:00.479] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:38:0) after 1m0s
[07:27:00.503] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:38:0)
[07:27:00.503] Timer tick, asking Buildbucket for the build status
[07:27:00.572] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:00.572] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:39:0) after 1m0s
[07:28:00.558] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:39:0)
[07:28:00.558] Timer tick, asking Buildbucket for the build status
[07:28:00.655] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:00.655] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:40:0) after 1m0s
[07:29:00.669] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:40:0)
[07:29:00.669] Timer tick, asking Buildbucket for the build status
[07:29:01.011] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:01.011] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:41:0) after 1m0s
[07:30:01.068] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:41:0)
[07:30:01.068] Timer tick, asking Buildbucket for the build status
[07:30:01.402] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:01.402] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:42:0) after 1m0s
[07:31:01.374] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:42:0)
[07:31:01.374] Timer tick, asking Buildbucket for the build status
[07:31:01.826] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:01.826] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:43:0) after 1m0s
[07:32:01.843] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:43:0)
[07:32:01.843] Timer tick, asking Buildbucket for the build status
[07:32:02.063] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:02.063] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:44:0) after 1m0s
[07:33:02.076] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:44:0)
[07:33:02.076] Timer tick, asking Buildbucket for the build status
[07:33:02.302] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:02.302] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:45:0) after 1m0s
[07:34:02.326] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:45:0)
[07:34:02.326] Timer tick, asking Buildbucket for the build status
[07:34:02.463] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:02.463] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:46:0) after 1m0s
[07:35:02.483] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:46:0)
[07:35:02.483] Timer tick, asking Buildbucket for the build status
[07:35:02.573] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:02.573] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:47:0) after 1m0s
[07:36:02.599] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:47:0)
[07:36:02.599] Timer tick, asking Buildbucket for the build status
[07:36:02.672] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:02.672] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:48:0) after 1m0s
[07:37:02.671] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:48:0)
[07:37:02.671] Timer tick, asking Buildbucket for the build status
[07:37:02.787] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:02.787] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:49:0) after 1m0s
[07:38:02.811] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:49:0)
[07:38:02.811] Timer tick, asking Buildbucket for the build status
[07:38:02.885] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:02.885] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:50:0) after 1m0s
[07:39:02.896] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:50:0)
[07:39:02.896] Timer tick, asking Buildbucket for the build status
[07:39:02.965] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:02.965] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:51:0) after 1m0s
[07:40:02.984] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:51:0)
[07:40:02.984] Timer tick, asking Buildbucket for the build status
[07:40:03.076] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:03.076] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:52:0) after 1m0s
[07:41:03.097] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:52:0)
[07:41:03.097] Timer tick, asking Buildbucket for the build status
[07:41:03.513] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:03.513] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:53:0) after 1m0s
[07:42:03.517] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:53:0)
[07:42:03.517] Timer tick, asking Buildbucket for the build status
[07:42:03.985] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:03.985] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:54:0) after 1m0s
[07:43:03.962] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:54:0)
[07:43:03.962] Timer tick, asking Buildbucket for the build status
[07:43:04.264] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:04.264] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:55:0) after 1m0s
[07:44:04.302] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:55:0)
[07:44:04.302] Timer tick, asking Buildbucket for the build status
[07:44:04.380] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:04.380] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:56:0) after 1m0s
[07:45:04.352] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:56:0)
[07:45:04.352] Timer tick, asking Buildbucket for the build status
[07:45:04.578] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:04.580] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:57:0) after 1m0s
[07:46:04.587] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:57:0)
[07:46:04.587] Timer tick, asking Buildbucket for the build status
[07:46:04.688] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:04.688] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:58:0) after 1m0s
[07:47:04.697] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:58:0)
[07:47:04.697] Timer tick, asking Buildbucket for the build status
[07:47:04.784] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:04.784] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:59:0) after 1m0s
[07:48:04.754] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:59:0)
[07:48:04.754] Timer tick, asking Buildbucket for the build status
[07:48:04.912] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:04.912] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:60:0) after 1m0s
[07:49:04.926] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:60:0)
[07:49:04.926] Timer tick, asking Buildbucket for the build status
[07:49:05.008] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:05.008] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:61:0) after 1m0s
[07:50:05.018] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:61:0)
[07:50:05.018] Timer tick, asking Buildbucket for the build status
[07:50:05.102] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:05.102] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:62:0) after 1m0s
[07:51:05.123] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:62:0)
[07:51:05.123] Timer tick, asking Buildbucket for the build status
[07:51:05.266] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:05.266] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:63:0) after 1m0s
[07:52:05.236] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:63:0)
[07:52:05.236] Timer tick, asking Buildbucket for the build status
[07:52:05.673] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:05.673] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:64:0) after 1m0s
[07:53:05.700] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:64:0)
[07:53:05.700] Timer tick, asking Buildbucket for the build status
[07:53:05.766] Build 8920027541999964544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:05.766] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9085457604039315072:65:0) after 1m0s
[07:53:32.856] Received PubSub notification, asking Buildbucket for the build status
[07:53:32.906] Build 8920027541999964544: status "COMPLETED", result "FAILURE", failure_reason "INFRA_FAILURE", cancelation_reason ""
[07:53:32.906] Buildbucket build:
{
"build": {
"bucket": "luci.v8.try",
"canary_preference": "AUTO",
"completed_ts": "1551599611865802",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1551595853766337",
"failure_reason": "INFRA_FAILURE",
"id": "8920027541999964544",
"parameters_json": "{\"builder_name\": \"v8_verify_flakes\", \"properties\": {}}",
"project": "v8",
"result": "FAILURE",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-f\"], \"inside_docker\": [\"1\", \"stock\"], \"python\": [\"2.7.6\"], \"image\": [\"chrome-trusty-18042300-b7223b463e3\"], \"kvm\": [\"0\"], \"gpu\": [\"none\"], \"id\": [\"swarm-docker-2-c4--000\"], \"server_version\": [\"4143-92e279d\"], \"machine_type\": [\"n1-standard-8\"], \"cores\": [\"8\"], \"host_class\": [\"multibot\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Ivy_Bridge_GCE\"], \"pool\": [\"luci.v8.try\"], \"caches\": [\"git\", \"goma_v2\", \"v8_builder_cache_nowait\", \"vpython\"]}}, \"ui\": {\"info\": \"Failure FunctionCallSample\\nFailure store\\nFailure not-hold-promises\\nFailure FunctionDetailsInlining\\nFailure Uncaught Exception\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/swarming/w/ir/cache\", \"temp_dir\": \"/b/swarming/w/ir/tmp/rt\"}, \"buildername\": \"v8_verify_flakes\", \"recipe\": \"v8/verify_flakes\", \"mastername\": \"tryserver.v8\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1551595853766337, \"tags\": [\"builder:v8_verify_flakes\", \"scheduler_invocation_id:9085457604039315072\", \"scheduler_job_id:v8/v8_verify_flakes\", \"user_agent:luci-scheduler\"], \"bucket\": \"luci.v8.try\", \"created_by\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"project\": \"v8\", \"id\": \"8920027541999964544\"}}, \"$recipe_engine/buildbucket\": {\"build\": {\"tags\": [{\"value\": \"v8_verify_flakes\", \"key\": \"builder\"}, {\"value\": \"9085457604039315072\", \"key\": \"scheduler_invocation_id\"}, {\"value\": \"v8/v8_verify_flakes\", \"key\": \"scheduler_job_id\"}, {\"value\": \"luci-scheduler\", \"key\": \"user_agent\"}], \"builder\": {\"project\": \"v8\", \"builder\": \"v8_verify_flakes\", \"bucket\": \"try\"}, \"infra\": {\"buildbucket\": {\"serviceConfigRevision\": \"ca0db2d762966d4b32d49d86567d9d7697438caf\"}, \"recipe\": {\"name\": \"v8/verify_flakes\", \"cipdPackage\": \"infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build\"}, \"swarming\": {\"hostname\": \"chromium-swarm.appspot.com\"}}, \"createdBy\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"id\": \"8920027541999964544\", \"input\": {}, \"createTime\": \"2019-03-03T06:50:53.766337Z\"}}, \"path_config\": \"generic\", \"bot_id\": \"swarm-docker-2-c4--000\"}}",
"service_account": "v8-try-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1551595856929682",
"status": "COMPLETED",
"status_changed_ts": "1551599612591571",
"tags": [
"builder:v8_verify_flakes",
"scheduler_invocation_id:9085457604039315072",
"scheduler_job_id:v8/v8_verify_flakes",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/v8/buildbucket/cr-buildbucket.appspot.com/8920027541999964544/+/annotations",
"swarming_tag:luci_project:v8",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:v8/verify_flakes",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:435b2360b6e6ff10",
"user_agent:luci-scheduler"
],
"updated_ts": "1551599612594301",
"url": "https://ci.chromium.org/b/8920027541999964544",
"utcnow_ts": "1551599612894341"
}
}
[07:53:32.907] Invocation finished in 1h2m40.469487658s with status FAILED