[02:29:12.775] New invocation is queued and will start shortly
[02:29:14.116] Starting the invocation (attempt 1)
[02:29:14.175] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:29:14.175] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:29:14.175] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9028669768414900320",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"f64375c0a789c510230f30f7efaf80ba00e45c30\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9028669768414900320",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/f64375c0a789c510230f30f7efaf80ba00e45c30",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f64375c0a789c510230f30f7efaf80ba00e45c30",
"gitiles_ref:refs/heads/master"
]
}
[02:29:15.754] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1605752954375474",
"id": "8863239706071367184",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f64375c0a789c510230f30f7efaf80ba00e45c30\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1605752955465742",
"tags": [
"build_address:luci.chromium.ci/win-asan/15714",
"builder:win-asan",
"buildset:commit/git/f64375c0a789c510230f30f7efaf80ba00e45c30",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f64375c0a789c510230f30f7efaf80ba00e45c30",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028669768414900320",
"scheduler_job_id:chromium/win-asan",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8863239706071367184/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1605752955465899",
"url": "https://ci.chromium.org/b/8863239706071367184",
"utcnow_ts": "1605752955739428"
}
}
[02:29:15.754] Task URL: https://ci.chromium.org/b/8863239706071367184
[02:29:15.754] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:2:0) after 1m0s
[02:30:15.825] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:2:0)
[02:30:15.825] Timer tick, asking Buildbucket for the build status
[02:30:16.132] Build 8863239706071367184: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:30:16.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:3:0) after 1m0s
[02:31:16.181] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:3:0)
[02:31:16.181] Timer tick, asking Buildbucket for the build status
[02:31:16.274] Build 8863239706071367184: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:31:16.274] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:4:0) after 1m0s
[02:32:16.343] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:4:0)
[02:32:16.343] Timer tick, asking Buildbucket for the build status
[02:32:16.631] Build 8863239706071367184: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:32:16.631] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:5:0) after 1m0s
[02:33:16.667] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:5:0)
[02:33:16.667] Timer tick, asking Buildbucket for the build status
[02:33:16.743] Build 8863239706071367184: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:33:16.744] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:6:0) after 1m0s
[02:34:07.450] Received PubSub notification, asking Buildbucket for the build status
[02:34:07.636] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:16.785] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:6:0)
[02:34:16.785] Timer tick, asking Buildbucket for the build status
[02:34:16.917] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:16.917] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:8:0) after 1m0s
[02:35:16.954] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:8:0)
[02:35:16.954] Timer tick, asking Buildbucket for the build status
[02:35:17.159] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:17.159] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:9:0) after 1m0s
[02:36:17.283] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:9:0)
[02:36:17.283] Timer tick, asking Buildbucket for the build status
[02:36:17.381] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:17.381] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:10:0) after 1m0s
[02:37:17.500] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:10:0)
[02:37:17.500] Timer tick, asking Buildbucket for the build status
[02:37:17.626] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:17.626] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:11:0) after 1m0s
[02:38:17.792] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:11:0)
[02:38:17.792] Timer tick, asking Buildbucket for the build status
[02:38:17.966] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:17.967] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:12:0) after 1m0s
[02:39:18.168] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:12:0)
[02:39:18.169] Timer tick, asking Buildbucket for the build status
[02:39:18.311] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:18.312] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:13:0) after 1m0s
[02:40:18.458] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:13:0)
[02:40:18.458] Timer tick, asking Buildbucket for the build status
[02:40:18.662] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:18.662] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:14:0) after 1m0s
[02:41:18.938] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:14:0)
[02:41:18.938] Timer tick, asking Buildbucket for the build status
[02:41:19.016] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:19.016] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:15:0) after 1m0s
[02:42:19.234] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:15:0)
[02:42:19.234] Timer tick, asking Buildbucket for the build status
[02:42:19.313] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:19.313] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:16:0) after 1m0s
[02:43:19.347] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:16:0)
[02:43:19.347] Timer tick, asking Buildbucket for the build status
[02:43:19.491] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:19.491] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:17:0) after 1m0s
[02:44:19.635] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:17:0)
[02:44:19.635] Timer tick, asking Buildbucket for the build status
[02:44:19.747] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:19.747] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:18:0) after 1m0s
[02:45:19.946] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:18:0)
[02:45:19.946] Timer tick, asking Buildbucket for the build status
[02:45:20.417] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:20.418] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:19:0) after 1m0s
[02:46:20.754] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:19:0)
[02:46:20.754] Timer tick, asking Buildbucket for the build status
[02:46:20.817] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:20.818] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:20:0) after 1m0s
[02:47:20.949] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:20:0)
[02:47:20.949] Timer tick, asking Buildbucket for the build status
[02:47:21.042] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:21.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:21:0) after 1m0s
[02:48:21.174] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:21:0)
[02:48:21.175] Timer tick, asking Buildbucket for the build status
[02:48:21.302] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:21.302] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:22:0) after 1m0s
[02:49:21.348] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:22:0)
[02:49:21.348] Timer tick, asking Buildbucket for the build status
[02:49:21.468] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:21.468] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:23:0) after 1m0s
[02:50:21.531] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:23:0)
[02:50:21.531] Timer tick, asking Buildbucket for the build status
[02:50:21.721] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:21.721] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:24:0) after 1m0s
[02:51:21.925] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:24:0)
[02:51:21.925] Timer tick, asking Buildbucket for the build status
[02:51:21.982] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:21.982] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:25:0) after 1m0s
[02:52:22.052] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:25:0)
[02:52:22.052] Timer tick, asking Buildbucket for the build status
[02:52:22.143] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:22.144] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:26:0) after 1m0s
[02:53:22.535] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:26:0)
[02:53:22.535] Timer tick, asking Buildbucket for the build status
[02:53:23.066] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:23.066] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:27:0) after 1m0s
[02:54:23.153] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:27:0)
[02:54:23.153] Timer tick, asking Buildbucket for the build status
[02:54:23.274] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:23.274] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:28:0) after 1m0s
[02:55:23.405] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:28:0)
[02:55:23.405] Timer tick, asking Buildbucket for the build status
[02:55:23.633] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:23.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:29:0) after 1m0s
[02:56:23.810] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:29:0)
[02:56:23.810] Timer tick, asking Buildbucket for the build status
[02:56:23.938] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:23.938] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:30:0) after 1m0s
[02:57:24.139] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:30:0)
[02:57:24.139] Timer tick, asking Buildbucket for the build status
[02:57:24.719] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:24.719] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:31:0) after 1m0s
[02:58:24.747] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:31:0)
[02:58:24.747] Timer tick, asking Buildbucket for the build status
[02:58:25.233] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:25.233] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:32:0) after 1m0s
[02:59:25.373] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:32:0)
[02:59:25.374] Timer tick, asking Buildbucket for the build status
[02:59:25.575] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:25.575] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:33:0) after 1m0s
[03:00:25.646] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:33:0)
[03:00:25.646] Timer tick, asking Buildbucket for the build status
[03:00:25.806] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:25.806] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:34:0) after 1m0s
[03:01:25.937] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:34:0)
[03:01:25.937] Timer tick, asking Buildbucket for the build status
[03:01:26.068] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:26.068] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:35:0) after 1m0s
[03:02:26.171] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:35:0)
[03:02:26.171] Timer tick, asking Buildbucket for the build status
[03:02:26.643] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:26.643] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:36:0) after 1m0s
[03:03:27.164] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:36:0)
[03:03:27.164] Timer tick, asking Buildbucket for the build status
[03:03:27.309] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:27.309] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:37:0) after 1m0s
[03:04:27.877] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:37:0)
[03:04:27.877] Timer tick, asking Buildbucket for the build status
[03:04:28.014] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:28.014] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:38:0) after 1m0s
[03:05:28.515] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:38:0)
[03:05:28.515] Timer tick, asking Buildbucket for the build status
[03:05:28.797] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:28.797] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:39:0) after 1m0s
[03:06:28.940] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:39:0)
[03:06:28.940] Timer tick, asking Buildbucket for the build status
[03:06:29.062] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:29.063] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:40:0) after 1m0s
[03:07:29.081] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:40:0)
[03:07:29.081] Timer tick, asking Buildbucket for the build status
[03:07:29.310] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:29.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:41:0) after 1m0s
[03:08:29.361] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:41:0)
[03:08:29.361] Timer tick, asking Buildbucket for the build status
[03:08:29.493] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:29.493] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:42:0) after 1m0s
[03:09:29.611] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:42:0)
[03:09:29.611] Timer tick, asking Buildbucket for the build status
[03:09:29.713] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:29.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:43:0) after 1m0s
[03:10:29.807] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:43:0)
[03:10:29.807] Timer tick, asking Buildbucket for the build status
[03:10:29.901] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:29.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:44:0) after 1m0s
[03:11:29.977] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:44:0)
[03:11:29.977] Timer tick, asking Buildbucket for the build status
[03:11:30.138] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:30.138] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:45:0) after 1m0s
[03:12:30.188] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:45:0)
[03:12:30.188] Timer tick, asking Buildbucket for the build status
[03:12:30.337] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:30.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:46:0) after 1m0s
[03:13:30.436] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:46:0)
[03:13:30.436] Timer tick, asking Buildbucket for the build status
[03:13:30.499] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:30.499] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:47:0) after 1m0s
[03:14:30.538] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:47:0)
[03:14:30.538] Timer tick, asking Buildbucket for the build status
[03:14:30.659] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:30.659] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:48:0) after 1m0s
[03:15:30.703] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:48:0)
[03:15:30.703] Timer tick, asking Buildbucket for the build status
[03:15:30.819] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:30.819] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:49:0) after 1m0s
[03:16:30.938] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:49:0)
[03:16:30.938] Timer tick, asking Buildbucket for the build status
[03:16:31.054] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:31.055] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:50:0) after 1m0s
[03:17:31.112] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:50:0)
[03:17:31.112] Timer tick, asking Buildbucket for the build status
[03:17:31.700] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:31.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:51:0) after 1m0s
[03:18:31.766] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:51:0)
[03:18:31.766] Timer tick, asking Buildbucket for the build status
[03:18:31.927] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:31.927] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:52:0) after 1m0s
[03:19:31.929] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:52:0)
[03:19:31.929] Timer tick, asking Buildbucket for the build status
[03:19:32.198] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:32.198] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:53:0) after 1m0s
[03:20:32.271] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:53:0)
[03:20:32.271] Timer tick, asking Buildbucket for the build status
[03:20:32.354] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:32.354] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:54:0) after 1m0s
[03:21:32.403] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:54:0)
[03:21:32.403] Timer tick, asking Buildbucket for the build status
[03:21:32.614] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:32.614] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:55:0) after 1m0s
[03:22:32.730] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:55:0)
[03:22:32.730] Timer tick, asking Buildbucket for the build status
[03:22:33.294] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:33.294] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:56:0) after 1m0s
[03:23:33.307] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:56:0)
[03:23:33.307] Timer tick, asking Buildbucket for the build status
[03:23:33.537] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:33.537] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:57:0) after 1m0s
[03:24:33.690] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:57:0)
[03:24:33.690] Timer tick, asking Buildbucket for the build status
[03:24:34.053] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:34.053] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:58:0) after 1m0s
[03:25:34.111] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:58:0)
[03:25:34.111] Timer tick, asking Buildbucket for the build status
[03:25:34.703] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:34.703] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:59:0) after 1m0s
[03:26:34.764] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:59:0)
[03:26:34.764] Timer tick, asking Buildbucket for the build status
[03:26:34.869] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:34.869] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:60:0) after 1m0s
[03:27:34.918] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:60:0)
[03:27:34.918] Timer tick, asking Buildbucket for the build status
[03:27:35.068] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:35.068] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:61:0) after 1m0s
[03:28:35.095] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:61:0)
[03:28:35.095] Timer tick, asking Buildbucket for the build status
[03:28:35.220] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:35.221] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:62:0) after 1m0s
[03:29:35.286] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:62:0)
[03:29:35.286] Timer tick, asking Buildbucket for the build status
[03:29:35.395] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:35.395] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:63:0) after 1m0s
[03:30:35.467] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:63:0)
[03:30:35.467] Timer tick, asking Buildbucket for the build status
[03:30:35.598] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:35.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:64:0) after 1m0s
[03:31:35.655] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:64:0)
[03:31:35.655] Timer tick, asking Buildbucket for the build status
[03:31:35.767] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:35.767] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:65:0) after 1m0s
[03:32:35.831] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:65:0)
[03:32:35.831] Timer tick, asking Buildbucket for the build status
[03:32:36.048] Build 8863239706071367184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:36.048] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028669768414900320:66:0) after 1m0s
[03:33:24.407] Received PubSub notification, asking Buildbucket for the build status
[03:33:24.571] Build 8863239706071367184: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:33:24.571] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1605756803054904",
"created_by": "project:chromium",
"created_ts": "1605752954375474",
"id": "8863239706071367184",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f64375c0a789c510230f30f7efaf80ba00e45c30\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 500.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"f64375c0a789c510230f30f7efaf80ba00e45c30\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-32-0-m5ra\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15714, \"clang_revision\": \"llvmorg-12-init-11780-g6ef07111-1\", \"got_angle_revision\": \"8a58e38bfd26672fe5d143a6f96a041f626bde33\", \"got_dawn_revision\": \"ce207be41fd6d9f1334ca6f251812511dc63faca\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"f64375c0a789c510230f30f7efaf80ba00e45c30\", \"got_revision_cp\": \"refs/heads/master@{#829050}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"060020166679a265a2bc8250f4d78a7a8c29de89\", \"got_v8_revision\": \"7e050e9a828e1540efd9687418f7fa8e6e2eaad7\", \"got_v8_revision_cp\": \"refs/heads/8.9.10@{#1}\", \"got_webrtc_revision\": \"afaca889d46b2c493de34a8f0771c89dea17bb1a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32614}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.win:win-asan\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f64375c0a789c510230f30f7efaf80ba00e45c30\", \"swarm_hashes_refs/heads/master(at){#829050}_without_patch\": {\"absl_hardening_tests\": \"990d737231e835dfe9827e0707e137bdbb0a3e28\", \"accessibility_unittests\": \"013a0dab417294c2272f09a827228bfe92f84ae5\", \"angle_unittests\": \"f10d98f814c829dacb63d38589136c3ec8d6395f\", \"app_shell_unittests\": \"f108f27cc2142ebffd1a41cf8dea316bcd23c20d\", \"aura_unittests\": \"2dbd6eb12df9b131b2b3cd839e9a0bcd594b6ae4\", \"base_unittests\": \"ef27f84c4c794297a2065e2f74a5b9e066fc349b\", \"base_util_unittests\": \"d3c07f270a830a1465079bba0241af290e49aa6e\", \"blink_common_unittests\": \"5fb8154c196b488a3f643b883222685359e160ad\", \"blink_fuzzer_unittests\": \"6e10af00ab32ed0be71512523e54188a5973b464\", \"blink_heap_unittests\": \"269ffb09c96c0186b96638da8ca774377f4185a4\", \"blink_platform_unittests\": \"b53d50c34c1e8491c81b488da31865935d7b682c\", \"blink_unittests\": \"1727e9c2b644483fa42f8f8d91720a31ef880655\", \"boringssl_crypto_tests\": \"aeca3c1bc5b282b79535ab9348be19472fac00cf\", \"boringssl_ssl_tests\": \"ccc68487c7ac10d164eed2ef2ed828755eb794c2\", \"browser_switcher_bho_unittests\": \"49bbefffec11ad089232b1e85262e352af5d09e4\", \"browser_tests\": \"b9b9ea1187bd6170be4ed13b7520e4c0c96beee1\", \"capture_unittests\": \"24d5871aafe20fa877b74c5b72cd97e7005e9951\", \"cast_unittests\": \"40c5d029ecf515ee124ebe59a2e547aaddbe9e12\", \"cc_unittests\": \"02fd6424685346f3bb18edb72a215230f60126a8\", \"chrome_app_unittests\": \"20f3a220392f6e8233d87042e62ff1955100c4e4\", \"chrome_cleaner_unittests\": \"79820a0787bd975d5ce7d4e288fea781ea601cf7\", \"chrome_elf_unittests\": \"b09e2983e2d4a8c230a70f653a431ca568fc30f1\", \"chromedriver_unittests\": \"7c0e0a1edbfcd3fc8bef591e01d30b7ca5d2a1d7\", \"components_browsertests\": \"8e1d96cfdee5b8b62231c94c8ea7f95d54dd5cbe\", \"components_unittests\": \"4ad60d0ad327d67cb946d9ed1432f7338e264ec4\", \"compositor_unittests\": \"ef1a16c2120a97a74a32237eebb7942aebcffcde\", \"content_browsertests\": \"fb0f080c820b242500575832afa206af128af0a0\", \"content_unittests\": \"8ab01ac787c2216e796d83ffc08e3f7c57d5d401\", \"courgette_unittests\": \"e377e8f1da2bcdce5356534daab4cd99da00f0fc\", \"crashpad_tests\": \"296afb8350a8b16470ed1ab3fcd8a9d612f4e796\", \"cronet_tests\": \"3973af4c1b953fb3b60d80fe568219f11767da83\", \"cronet_unittests\": \"13e7d506c1c99cdbd0135943439b4aa2046d1fdd\", \"crypto_unittests\": \"74bbadfcc7484afeda584c458f146270f44b26c5\", \"delayloads_unittests\": \"bffb886eb2403816fa9a443fd0c617e95418a11a\", \"device_unittests\": \"25b88d1d6644ca38a84656aca3ad2016b68d9d9e\", \"display_unittests\": \"956d4930264316609a8c0424d2b2aa616abab712\", \"elevation_service_unittests\": \"dad3c9091e234c019cb51af60f6e18a96dc4f24a\", \"events_unittests\": \"1a0c75fdb7a13b5079b60ad8f3b8d7bcbff50dc1\", \"extensions_browsertests\": \"64d172efaeb40a3f2f606a972b130defe2e85ea0\", \"extensions_unittests\": \"4033957981c9791861f11a4c8cbf221344f46bec\", \"filesystem_service_unittests\": \"c2c2e097dfccde0d672f037420b6cc5646a32dd7\", \"gcm_unit_tests\": \"174db6e2036070ec06f14ed1b37eeef2aceb24db\", \"gcp_unittests\": \"348510f5a167172760c615dfa8fe9af8768bedfc\", \"gfx_unittests\": \"4dc80963ccdf4b853f637eda7ff8e2602414e27b\", \"gin_unittests\": \"3b05dd002149f658838bc125d0f2faeaf0c58d3c\", \"google_apis_unittests\": \"1a83278ace65a966804d51993439415e8ebe4f50\", \"gpu_unittests\": \"c2f248dda92aef61739b926bd24e31b781b8b6bc\", \"gwp_asan_unittests\": \"21c693dac7952cca6c5754444b197f514c98dbe9\", \"headless_browsertests\": \"1426aa92c69c2d64bb50a34cffeb93f3881313a3\", \"headless_unittests\": \"5cef848f23d8f5e501caf9e21b394c480a2638eb\", \"install_static_unittests\": \"78522ace93af17c77b23f4cbadd372a1762a44cf\", \"installer_util_unittests\": \"2b0206d8c94fe83cf729fe184dcd5b24e485003d\", \"interactive_ui_tests\": \"32d42cc96e5e643a1c70962c129f1b896927e741\", \"ipc_tests\": \"f7ba15c3d88c835f851f4dc9cdd21d0a62724c54\", \"jingle_unittests\": \"91b0eb664d3b07da3b487c69223474b95fe2fcf6\", \"latency_unittests\": \"c26246f041df9a806888d14648840fc075aa9acf\", \"libjingle_xmpp_unittests\": \"896ddfc6230fe0c2ff0f06f472350441f2557d39\", \"liburlpattern_unittests\": \"e0ee7d7944b9ee504440e4f9e627b93e32e9a9fd\", \"media_blink_unittests\": \"1a84c545af49aa97da7b54695ff2a5b4a0e230da\", \"media_unittests\": \"fc28d399f2cbd81ac859de950c2eedf451df940e\", \"message_center_unittests\": \"751a2d53cbc253a948c299f9cd1473bdb65b76b1\", \"midi_unittests\": \"a176b2a28a39aec90ab9dba1f2c8d0f8ca2ce928\", \"mojo_core_unittests\": \"686e6d4f003c9899ab940db271f66aeab9f9628c\", \"mojo_unittests\": \"b5b75a6a61b10653052f85fd2ed25d6b4216ee2f\", \"nacl_loader_unittests\": \"8196dd538d590ada306483551cc60e502d254abd\", \"native_theme_unittests\": \"c88f85384cb6cfc56a72536f43437a43da683444\", \"net_unittests\": \"8f940cb4816dd047f88c053bae68467d33b49bb5\", \"notification_helper_unittests\": \"24d828d90c14c3347bc4dac1f11c23ef46c6ff16\", \"pdf_unittests\": \"b56466881db4bf6b1fdcfcb14d7f3781e6fd79f4\", \"perfetto_unittests\": \"1ce264301dd83db05ef84614d0f87e67bcd9022d\", \"ppapi_unittests\": \"f79cf58b0fa21e8d2729a399a82204413595b6fe\", \"printing_unittests\": \"5f15b58da215526336f03038fef443ec09a4e297\", \"remoting_unittests\": \"248932659b1701782e7a7a6490116214b6adeabe\", \"sbox_integration_tests\": \"c852b4fb2f62199851e6a8df78fcbcdf0bd5f99c\", \"sbox_unittests\": \"dbcaa926e2dec8bc79372433699785a0e91f229c\", \"sbox_validation_tests\": \"62f9fc90634eaf0f706ee4dad2a061eecb048256\", \"service_manager_unittests\": \"b0a62c957241905f56c7d851cc4364647fdfe631\", \"services_unittests\": \"49d1c3f779bbf328bdbcde34082b815595460c8a\", \"setup_unittests\": \"f787b919e8cf3a6c3443c3151b1a9469c6e01d4b\", \"shell_dialogs_unittests\": \"3e0a5c10e776122c3a02ed709a478e39477196a9\", \"skia_unittests\": \"f43074b6a7bcc906b46e162a99c0a344469acd88\", \"snapshot_unittests\": \"3900e7c1950e5d3f20ca0e45f79bb2a46699b96d\", \"sql_unittests\": \"2d39ac066e18d31a63e59d121132b0e0194a6367\", \"storage_unittests\": \"c4f05a5c014b8f5017be794e9cbd3f28e6491ebb\", \"sync_integration_tests\": \"ab43f24432c5446c4e387153e94b047c592a895e\", \"traffic_annotation_auditor_unittests\": \"4a32b0b4184935d8ab2f1e5b26be9d1636c27059\", \"ui_base_unittests\": \"51ae43372672dd043d2113520ff72452cbf608e7\", \"ui_touch_selection_unittests\": \"bca0040e259a07d50cff0b02e82b3fe4e168d4cb\", \"unit_tests\": \"132efe597f3da6ab0a1db9eb2c683baa7efb04d8\", \"updater_tests\": \"b0db16390ce1776c6749c71fa8d3da0d0c72b735\", \"url_unittests\": \"435ab450c63cf22f49b88a48cd72c9c7eaad85b5\", \"views_unittests\": \"bc33b0a2e0017c72462349fb900bb27c2f5c1313\", \"viz_unittests\": \"397dd408997bfc4d82c57d3e07c1e49e3a370dc4\", \"vr_common_unittests\": \"2c228c8d2be87cc22298c5407b205ae24aa77706\", \"vr_pixeltests\": \"095d891feba21d8e3887cec11e0adc7fdeba2bf5\", \"weblayer_browsertests\": \"696b90f2d9e8ce1dee0819a67aa7678bbea8e772\", \"weblayer_unittests\": \"9df01deddc8c9968d24ae6081cee5c2b8259c37b\", \"wm_unittests\": \"65e2281ed6d2a7da8636bed326ee0078315b9ca0\", \"wtf_unittests\": \"4c9dfc0d4762916b0aa9a930649c2706a7fd85b6\", \"zlib_unittests\": \"aea3552d16ba0680d919671c4bb9d33cf38f264c\", \"zucchini_unittests\": \"38504db4100acea5a66b3efe02fbfbb512ec3770\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-0-m5ra\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5635-1756fdb\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1605753246476767",
"status": "COMPLETED",
"status_changed_ts": "1605756803537006",
"tags": [
"build_address:luci.chromium.ci/win-asan/15714",
"builder:win-asan",
"buildset:commit/git/f64375c0a789c510230f30f7efaf80ba00e45c30",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f64375c0a789c510230f30f7efaf80ba00e45c30",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028669768414900320",
"scheduler_job_id:chromium/win-asan",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8863239706071367184/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Windows",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:4ff7272bdf601710",
"user_agent:luci-scheduler"
],
"updated_ts": "1605756803611354",
"url": "https://ci.chromium.org/b/8863239706071367184",
"utcnow_ts": "1605756804563208"
}
}
[03:33:24.572] Invocation finished in 1h4m11.814148976s with status SUCCEEDED