[22:20:20.953] New invocation is queued and will start shortly
[22:20:22.305] Starting the invocation (attempt 1)
[22:20:22.335] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:20:22.335] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:20:22.335] Buildbucket request:
{
"bucket": "luci.v8.ci",
"client_operation_id": "9107142398464200656",
"parameters_json": "{\"builder_name\":\"Linux ASAN Builder\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/v8/v8\",\"revision\":\"289729bb6d904a58f7783b93736112a29b3ac589\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux ASAN Builder",
"scheduler_invocation_id:9107142398464200656",
"scheduler_job_id:v8/Linux ASAN Builder",
"user_agent:luci-scheduler",
"buildset:commit/git/289729bb6d904a58f7783b93736112a29b3ac589",
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/289729bb6d904a58f7783b93736112a29b3ac589",
"gitiles_ref:refs/heads/master"
]
}
[22:20:23.627] Buildbucket response:
{
"build": {
"bucket": "luci.v8.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1530915622410420",
"id": "8941712336274197280",
"lease_expiration_ts": "1530958823318450",
"lease_key": "456800436",
"parameters_json": "{\"builder_name\": \"Linux ASAN Builder\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"289729bb6d904a58f7783b93736112a29b3ac589\"}}",
"project": "v8",
"result_details_json": "null",
"service_account": "v8-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1530915623482730",
"tags": [
"build_address:luci.v8.ci/Linux ASAN Builder/6953",
"builder:Linux ASAN Builder",
"buildset:commit/git/289729bb6d904a58f7783b93736112a29b3ac589",
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/289729bb6d904a58f7783b93736112a29b3ac589",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9107142398464200656",
"scheduler_job_id:v8/Linux ASAN Builder",
"swarming_dimension:cpu:x86-64",
"swarming_dimension:host_class:large_disk",
"swarming_dimension:os:Ubuntu-14.04",
"swarming_dimension:pool:luci.v8.ci",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:build_address:luci.v8.ci/Linux ASAN Builder/6953",
"swarming_tag:buildbucket_bucket:luci.v8.ci",
"swarming_tag:buildbucket_build_id:8941712336274197280",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:4e8cd6081d2622e2214b4bbd0cdf5e2ba5406635",
"swarming_tag:builder:Linux ASAN Builder",
"swarming_tag:buildset:commit/git/289729bb6d904a58f7783b93736112a29b3ac589",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/289729bb6d904a58f7783b93736112a29b3ac589",
"swarming_tag:cpu:x86-64",
"swarming_tag:gitiles_ref:refs/heads/master",
"swarming_tag:host_class:large_disk",
"swarming_tag:log_location:logdog://logs.chromium.org/v8/buildbucket/cr-buildbucket.appspot.com/8941712336274197280/+/annotations",
"swarming_tag:luci_project:v8",
"swarming_tag:os:Ubuntu-14.04",
"swarming_tag:pool:luci.v8.ci",
"swarming_tag:priority:30",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_tag:scheduler_invocation_id:9107142398464200656",
"swarming_tag:scheduler_job_id:v8/Linux ASAN Builder",
"swarming_tag:service_account:v8-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"swarming_tag:swarming.pool.template:skip",
"swarming_tag:swarming.pool.version:dfa6096ea25a84c1e3f6fa3a9f293c6aecf802a3",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3e8a801908cebe10",
"user_agent:luci-scheduler"
],
"updated_ts": "1530915623482830",
"url": "https://ci.chromium.org/p/v8/builders/luci.v8.ci/Linux ASAN Builder/6953",
"utcnow_ts": "1530915623611780"
}
}
[22:20:23.627] Task URL: https://ci.chromium.org/p/v8/builders/luci.v8.ci/Linux ASAN Builder/6953
[22:20:23.627] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:2:0) after 1m0s
[22:21:23.644] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:2:0)
[22:21:23.644] Timer tick, asking Buildbucket for the build status
[22:21:23.716] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:21:23.716] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:3:0) after 1m0s
[22:22:23.745] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:3:0)
[22:22:23.745] Timer tick, asking Buildbucket for the build status
[22:22:23.864] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:22:23.864] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:4:0) after 1m0s
[22:23:23.927] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:4:0)
[22:23:23.927] Timer tick, asking Buildbucket for the build status
[22:23:24.015] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:23:24.015] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:5:0) after 1m0s
[22:24:24.103] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:5:0)
[22:24:24.103] Timer tick, asking Buildbucket for the build status
[22:24:24.131] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:24:24.131] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:6:0) after 1m0s
[22:25:24.167] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:6:0)
[22:25:24.167] Timer tick, asking Buildbucket for the build status
[22:25:24.223] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:25:24.223] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:7:0) after 1m0s
[22:26:24.241] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:7:0)
[22:26:24.241] Timer tick, asking Buildbucket for the build status
[22:26:24.289] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:26:24.289] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:8:0) after 1m0s
[22:27:24.433] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:8:0)
[22:27:24.433] Timer tick, asking Buildbucket for the build status
[22:27:24.687] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:27:24.687] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:9:0) after 1m0s
[22:28:24.703] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:9:0)
[22:28:24.703] Timer tick, asking Buildbucket for the build status
[22:28:24.740] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:28:24.740] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:10:0) after 1m0s
[22:29:24.757] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:10:0)
[22:29:24.757] Timer tick, asking Buildbucket for the build status
[22:29:24.852] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:29:24.852] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:11:0) after 1m0s
[22:30:24.871] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:11:0)
[22:30:24.871] Timer tick, asking Buildbucket for the build status
[22:30:24.940] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:30:24.940] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:12:0) after 1m0s
[22:31:24.960] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:12:0)
[22:31:24.960] Timer tick, asking Buildbucket for the build status
[22:31:24.997] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:31:24.997] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:13:0) after 1m0s
[22:32:25.032] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:13:0)
[22:32:25.032] Timer tick, asking Buildbucket for the build status
[22:32:25.068] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:32:25.068] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:14:0) after 1m0s
[22:33:25.110] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:14:0)
[22:33:25.110] Timer tick, asking Buildbucket for the build status
[22:33:25.159] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:33:25.159] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:15:0) after 1m0s
[22:34:25.179] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:15:0)
[22:34:25.179] Timer tick, asking Buildbucket for the build status
[22:34:25.235] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:34:25.235] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:16:0) after 1m0s
[22:35:25.496] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:16:0)
[22:35:25.496] Timer tick, asking Buildbucket for the build status
[22:35:25.549] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:35:25.549] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:17:0) after 1m0s
[22:36:25.645] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:17:0)
[22:36:25.645] Timer tick, asking Buildbucket for the build status
[22:36:25.699] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:36:25.699] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:18:0) after 1m0s
[22:37:25.808] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:18:0)
[22:37:25.808] Timer tick, asking Buildbucket for the build status
[22:37:25.974] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:37:25.974] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:19:0) after 1m0s
[22:38:26.011] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:19:0)
[22:38:26.011] Timer tick, asking Buildbucket for the build status
[22:38:26.078] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:38:26.078] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:20:0) after 1m0s
[22:39:26.100] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:20:0)
[22:39:26.100] Timer tick, asking Buildbucket for the build status
[22:39:26.186] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:39:26.186] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:21:0) after 1m0s
[22:40:26.244] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:21:0)
[22:40:26.244] Timer tick, asking Buildbucket for the build status
[22:40:26.325] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:40:26.325] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:22:0) after 1m0s
[22:41:26.351] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:22:0)
[22:41:26.351] Timer tick, asking Buildbucket for the build status
[22:41:26.456] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:41:26.456] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:23:0) after 1m0s
[22:42:26.487] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:23:0)
[22:42:26.487] Timer tick, asking Buildbucket for the build status
[22:42:26.521] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:42:26.521] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:24:0) after 1m0s
[22:43:26.538] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:24:0)
[22:43:26.538] Timer tick, asking Buildbucket for the build status
[22:43:26.648] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:43:26.648] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:25:0) after 1m0s
[22:44:26.669] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:25:0)
[22:44:26.669] Timer tick, asking Buildbucket for the build status
[22:44:26.706] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:44:26.706] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:26:0) after 1m0s
[22:45:26.725] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:26:0)
[22:45:26.725] Timer tick, asking Buildbucket for the build status
[22:45:26.773] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:45:26.773] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:27:0) after 1m0s
[22:46:26.846] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:27:0)
[22:46:26.846] Timer tick, asking Buildbucket for the build status
[22:46:26.986] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:46:26.986] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:28:0) after 1m0s
[22:47:27.006] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:28:0)
[22:47:27.006] Timer tick, asking Buildbucket for the build status
[22:47:27.047] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:47:27.047] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:29:0) after 1m0s
[22:48:27.062] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:29:0)
[22:48:27.062] Timer tick, asking Buildbucket for the build status
[22:48:27.125] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:48:27.125] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:30:0) after 1m0s
[22:49:27.143] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:30:0)
[22:49:27.143] Timer tick, asking Buildbucket for the build status
[22:49:27.258] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:49:27.258] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:31:0) after 1m0s
[22:50:27.278] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:31:0)
[22:50:27.278] Timer tick, asking Buildbucket for the build status
[22:50:27.315] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:50:27.315] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:32:0) after 1m0s
[22:51:27.332] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:32:0)
[22:51:27.332] Timer tick, asking Buildbucket for the build status
[22:51:27.445] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:51:27.445] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:33:0) after 1m0s
[22:52:27.490] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:33:0)
[22:52:27.490] Timer tick, asking Buildbucket for the build status
[22:52:27.586] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:52:27.586] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:34:0) after 1m0s
[22:53:27.604] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:34:0)
[22:53:27.604] Timer tick, asking Buildbucket for the build status
[22:53:27.901] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:53:27.901] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:35:0) after 1m0s
[22:54:27.930] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:35:0)
[22:54:27.930] Timer tick, asking Buildbucket for the build status
[22:54:28.151] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:54:28.151] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:36:0) after 1m0s
[22:55:28.170] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:36:0)
[22:55:28.170] Timer tick, asking Buildbucket for the build status
[22:55:28.257] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:55:28.257] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:37:0) after 1m0s
[22:56:28.275] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:37:0)
[22:56:28.275] Timer tick, asking Buildbucket for the build status
[22:56:28.346] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:56:28.346] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:38:0) after 1m0s
[22:57:28.363] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:38:0)
[22:57:28.363] Timer tick, asking Buildbucket for the build status
[22:57:28.442] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:57:28.442] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:39:0) after 1m0s
[22:58:28.456] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:39:0)
[22:58:28.456] Timer tick, asking Buildbucket for the build status
[22:58:28.570] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:58:28.570] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:40:0) after 1m0s
[22:59:28.585] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:40:0)
[22:59:28.585] Timer tick, asking Buildbucket for the build status
[22:59:28.880] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:59:28.880] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:41:0) after 1m0s
[23:00:28.932] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:41:0)
[23:00:28.932] Timer tick, asking Buildbucket for the build status
[23:00:28.989] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:00:28.989] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:42:0) after 1m0s
[23:01:29.028] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:42:0)
[23:01:29.028] Timer tick, asking Buildbucket for the build status
[23:01:29.122] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:01:29.122] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:43:0) after 1m0s
[23:02:29.184] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:43:0)
[23:02:29.184] Timer tick, asking Buildbucket for the build status
[23:02:29.220] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:02:29.220] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:44:0) after 1m0s
[23:03:29.361] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:44:0)
[23:03:29.361] Timer tick, asking Buildbucket for the build status
[23:03:29.394] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:03:29.394] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:45:0) after 1m0s
[23:04:29.519] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:45:0)
[23:04:29.519] Timer tick, asking Buildbucket for the build status
[23:04:29.565] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:04:29.565] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:46:0) after 1m0s
[23:05:29.582] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:46:0)
[23:05:29.582] Timer tick, asking Buildbucket for the build status
[23:05:29.728] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:05:29.728] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:47:0) after 1m0s
[23:06:29.855] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:47:0)
[23:06:29.855] Timer tick, asking Buildbucket for the build status
[23:06:29.922] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:06:29.922] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:48:0) after 1m0s
[23:07:29.963] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:48:0)
[23:07:29.963] Timer tick, asking Buildbucket for the build status
[23:07:30.055] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:07:30.055] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:49:0) after 1m0s
[23:08:30.178] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:49:0)
[23:08:30.178] Timer tick, asking Buildbucket for the build status
[23:08:30.401] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:08:30.401] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:50:0) after 1m0s
[23:09:30.460] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:50:0)
[23:09:30.460] Timer tick, asking Buildbucket for the build status
[23:09:30.527] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:09:30.527] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:51:0) after 1m0s
[23:10:30.552] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:51:0)
[23:10:30.552] Timer tick, asking Buildbucket for the build status
[23:10:30.776] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:10:30.776] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:52:0) after 1m0s
[23:11:30.854] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:52:0)
[23:11:30.854] Timer tick, asking Buildbucket for the build status
[23:11:31.849] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:11:31.849] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:53:0) after 1m0s
[23:12:31.872] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:53:0)
[23:12:31.872] Timer tick, asking Buildbucket for the build status
[23:12:32.156] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:12:32.156] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:54:0) after 1m0s
[23:13:32.330] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:54:0)
[23:13:32.330] Timer tick, asking Buildbucket for the build status
[23:13:32.443] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:13:32.443] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:55:0) after 1m0s
[23:14:32.462] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:55:0)
[23:14:32.462] Timer tick, asking Buildbucket for the build status
[23:14:32.493] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:14:32.493] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:56:0) after 1m0s
[23:15:32.512] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:56:0)
[23:15:32.512] Timer tick, asking Buildbucket for the build status
[23:15:32.551] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:15:32.551] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:57:0) after 1m0s
[23:16:32.568] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:57:0)
[23:16:32.568] Timer tick, asking Buildbucket for the build status
[23:16:32.616] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:16:32.616] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:58:0) after 1m0s
[23:17:32.635] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:58:0)
[23:17:32.635] Timer tick, asking Buildbucket for the build status
[23:17:32.744] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:17:32.744] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:59:0) after 1m0s
[23:18:32.763] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:59:0)
[23:18:32.763] Timer tick, asking Buildbucket for the build status
[23:18:32.811] Build 8941712336274197280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:18:32.811] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:60:0) after 1m0s
[23:19:01.898] Received PubSub notification, asking Buildbucket for the build status
[23:19:02.003] Build 8941712336274197280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:32.830] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:60:0)
[23:19:32.830] Timer tick, asking Buildbucket for the build status
[23:19:32.865] Build 8941712336274197280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:32.865] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:62:0) after 1m0s
[23:20:32.894] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:62:0)
[23:20:32.894] Timer tick, asking Buildbucket for the build status
[23:20:32.943] Build 8941712336274197280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:32.944] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:63:0) after 1m0s
[23:21:32.976] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:63:0)
[23:21:32.976] Timer tick, asking Buildbucket for the build status
[23:21:33.033] Build 8941712336274197280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:33.033] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:64:0) after 1m0s
[23:22:33.073] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:64:0)
[23:22:33.073] Timer tick, asking Buildbucket for the build status
[23:22:33.206] Build 8941712336274197280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:33.206] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:9107142398464200656:65:0) after 1m0s
[23:23:27.191] Received PubSub notification, asking Buildbucket for the build status
[23:23:27.223] Build 8941712336274197280: status "COMPLETED", result "FAILURE", failure_reason "INFRA_FAILURE", cancelation_reason ""
[23:23:27.223] Buildbucket build:
{
"build": {
"bucket": "luci.v8.ci",
"canary_preference": "AUTO",
"completed_ts": "1530919405723620",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1530915622410420",
"failure_reason": "INFRA_FAILURE",
"id": "8941712336274197280",
"parameters_json": "{\"builder_name\": \"Linux ASAN Builder\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"289729bb6d904a58f7783b93736112a29b3ac589\"}}",
"project": "v8",
"result": "FAILURE",
"result_details_json": "{\"ui\": {\"info\": \"Failure bot_update\\nFailure Uncaught Exception\"}, \"build_run_result\": {\"infraFailure\": {\"text\": \"recipe infra failure: Uncaught Exception: KeyError('root',)\", \"bootstrapperCallStack\": [], \"type\": \"RECIPE_INFRA_FAILURE\"}, \"recipeResult\": {\"failure\": {\"exception\": {\"traceback\": [\"Traceback (most recent call last):\", \" File \\\"/b/swarming/w/ir/kitchen-checkout/recipe_engine/recipe_engine/run.py\\\", line 287, in _new_run\", \" recipe_result = recipe_script.run(api, self.properties, self.environ)\", \" File \\\"/b/swarming/w/ir/kitchen-checkout/recipe_engine/recipe_engine/loader.py\\\", line 108, in run\", \" self.run_steps, properties, environ, self.PROPERTIES, api=api)\", \" File \\\"/b/swarming/w/ir/kitchen-checkout/recipe_engine/recipe_engine/loader.py\\\", line 636, in invoke_with_properties\", \" arg_names, **additional_args)\", \" File \\\"/b/swarming/w/ir/kitchen-checkout/recipe_engine/recipe_engine/loader.py\\\", line 599, in _invoke_with_properties\", \" return callable_obj(*props, **additional_args)\", \" File \\\"/b/swarming/w/ir/kitchen-checkout/build/scripts/slave/recipes/chromium.py\\\", line 51, in RunSteps\", \" api.chromium_tests.main_waterfall_steps()\", \" File \\\"/b/swarming/w/ir/kitchen-checkout/recipe_engine/recipe_engine/recipe_api.py\\\", line 759, in _inner\", \" return func(*a, **kw)\", \" File \\\"/b/swarming/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/chromium_tests/api.py\\\", line 912, in main_waterfall_steps\", \" update_step, bot_db = self.prepare_checkout(bot_config)\", \" File \\\"/b/swarming/w/ir/kitchen-checkout/recipe_engine/recipe_engine/recipe_api.py\\\", line 759, in _inner\", \" return func(*a, **kw)\", \" File \\\"/b/swarming/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/chromium_tests/api.py\\\", line 218, in prepare_checkout\", \" bot_config, root_solution_revision, disable_syntax_validation)\", \" File \\\"/b/swarming/w/ir/kitchen-checkout/recipe_engine/recipe_engine/recipe_api.py\\\", line 759, in _inner\", \" return func(*a, **kw)\", \" File \\\"/b/swarming/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/chromium_checkout/api.py\\\", line 100, in ensure_checkout\", \" disable_syntax_validation=disable_syntax_validation)\", \" File \\\"/b/swarming/w/ir/kitchen-checkout/recipe_engine/recipe_engine/recipe_api.py\\\", line 759, in _inner\", \" return func(*a, **kw)\", \" File \\\"/b/swarming/w/ir/kitchen-checkout/depot_tools/recipes/recipe_modules/bot_update/api.py\\\", line 307, in ensure_checkout\", \" co_root = result['root']\", \"KeyError: 'root'\"]}, \"humanReason\": \"Uncaught Exception: KeyError('root',)\"}}, \"recipeExitCode\": {\"value\": 1}, \"recipe\": {\"name\": \"chromium\", \"repository\": \"\", \"revision\": \"\"}, \"annotationUrl\": \"logdog://logs.chromium.org/v8/buildbucket/cr-buildbucket.appspot.com/8941712336274197280/+/annotations\"}, \"swarming\": {\"bot_dimensions\": {\"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-f\"], \"inside_docker\": [\"0\"], \"python\": [\"2.7.6\"], \"image\": [\"chrome-trusty-18042300-b7223b463e3\"], \"kvm\": [\"0\"], \"gpu\": [\"none\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Ivy_Bridge_GCE\"], \"server_version\": [\"3675-21ffa58\"], \"machine_type\": [\"n1-standard-8\"], \"host_class\": [\"large_disk\"], \"cores\": [\"8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"id\": [\"swarm833-c4\"], \"pool\": [\"luci.v8.ci\"], \"caches\": [\"builder_6ddb9bc087adf6fc27054a26ebb52d1bb4969dab0ae7b6276027faf773237aae_v2\", \"builder_ded865e34a9093a5cba6d7fe7a1041309519f146e5060747e23c104b1d71922c_v2\", \"git\", \"goma_v2\", \"vpython\"]}}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/swarming/w/ir/cache\", \"temp_dir\": \"/b/swarming/w/ir/tmp/rt\"}, \"buildnumber\": 6953, \"repository\": \"https://chromium.googlesource.com/v8/v8\", \"buildername\": \"Linux ASAN Builder\", \"recipe\": \"chromium\", \"mastername\": \"client.v8.fyi\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1530915622410420, \"tags\": [\"builder:Linux ASAN Builder\", \"buildset:commit/git/289729bb6d904a58f7783b93736112a29b3ac589\", \"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/289729bb6d904a58f7783b93736112a29b3ac589\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9107142398464200656\", \"scheduler_job_id:v8/Linux ASAN Builder\", \"user_agent:luci-scheduler\"], \"bucket\": \"luci.v8.ci\", \"created_by\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"project\": \"v8\", \"id\": \"8941712336274197280\"}}, \"branch\": \"refs/heads/master\", \"path_config\": \"generic\", \"revision\": \"289729bb6d904a58f7783b93736112a29b3ac589\", \"bot_id\": \"swarm833-c4\"}}",
"service_account": "v8-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1530919140912010",
"status": "COMPLETED",
"status_changed_ts": "1530919406735300",
"tags": [
"build_address:luci.v8.ci/Linux ASAN Builder/6953",
"builder:Linux ASAN Builder",
"buildset:commit/git/289729bb6d904a58f7783b93736112a29b3ac589",
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/289729bb6d904a58f7783b93736112a29b3ac589",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9107142398464200656",
"scheduler_job_id:v8/Linux ASAN Builder",
"swarming_dimension:cpu:x86-64",
"swarming_dimension:host_class:large_disk",
"swarming_dimension:os:Ubuntu-14.04",
"swarming_dimension:pool:luci.v8.ci",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:build_address:luci.v8.ci/Linux ASAN Builder/6953",
"swarming_tag:buildbucket_bucket:luci.v8.ci",
"swarming_tag:buildbucket_build_id:8941712336274197280",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:4e8cd6081d2622e2214b4bbd0cdf5e2ba5406635",
"swarming_tag:builder:Linux ASAN Builder",
"swarming_tag:buildset:commit/git/289729bb6d904a58f7783b93736112a29b3ac589",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/289729bb6d904a58f7783b93736112a29b3ac589",
"swarming_tag:cpu:x86-64",
"swarming_tag:gitiles_ref:refs/heads/master",
"swarming_tag:host_class:large_disk",
"swarming_tag:log_location:logdog://logs.chromium.org/v8/buildbucket/cr-buildbucket.appspot.com/8941712336274197280/+/annotations",
"swarming_tag:luci_project:v8",
"swarming_tag:os:Ubuntu-14.04",
"swarming_tag:pool:luci.v8.ci",
"swarming_tag:priority:30",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_tag:scheduler_invocation_id:9107142398464200656",
"swarming_tag:scheduler_job_id:v8/Linux ASAN Builder",
"swarming_tag:service_account:v8-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"swarming_tag:swarming.pool.template:skip",
"swarming_tag:swarming.pool.version:dfa6096ea25a84c1e3f6fa3a9f293c6aecf802a3",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3e8a801908cebe10",
"user_agent:luci-scheduler"
],
"updated_ts": "1530919406797880",
"url": "https://ci.chromium.org/p/v8/builders/luci.v8.ci/Linux ASAN Builder/6953",
"utcnow_ts": "1530919407213210"
}
}
[23:23:27.223] Invocation finished in 1h3m6.285516995s with status FAILED