[04:18:40.514] New invocation is queued and will start shortly
[04:18:41.865] Starting the invocation (attempt 1)
[04:18:41.953] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:18:41.953] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:18:41.953] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9030203030069705152",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"fa808fd707ff7ef4c0da2040a2cec9eb3b712da2\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9030203030069705152",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/fa808fd707ff7ef4c0da2040a2cec9eb3b712da2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/fa808fd707ff7ef4c0da2040a2cec9eb3b712da2",
"gitiles_ref:refs/heads/master"
]
}
[04:18:42.682] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1604290722129826",
"id": "8864772967710926224",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"fa808fd707ff7ef4c0da2040a2cec9eb3b712da2\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1604290722557862",
"tags": [
"build_address:luci.chromium.ci/win-asan/15406",
"builder:win-asan",
"buildset:commit/git/fa808fd707ff7ef4c0da2040a2cec9eb3b712da2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/fa808fd707ff7ef4c0da2040a2cec9eb3b712da2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030203030069705152",
"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/8864772967710926224/+/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": "1604290722558015",
"url": "https://ci.chromium.org/b/8864772967710926224",
"utcnow_ts": "1604290722666799"
}
}
[04:18:42.682] Task URL: https://ci.chromium.org/b/8864772967710926224
[04:18:42.684] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:2:0) after 1m0s
[04:18:44.645] Received PubSub notification, asking Buildbucket for the build status
[04:18:44.721] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:42.757] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:2:0)
[04:19:42.757] Timer tick, asking Buildbucket for the build status
[04:19:42.851] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:42.851] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:4:0) after 1m0s
[04:20:42.889] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:4:0)
[04:20:42.889] Timer tick, asking Buildbucket for the build status
[04:20:43.048] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:43.048] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:5:0) after 1m0s
[04:21:43.118] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:5:0)
[04:21:43.118] Timer tick, asking Buildbucket for the build status
[04:21:43.247] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:43.247] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:6:0) after 1m0s
[04:22:43.272] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:6:0)
[04:22:43.272] Timer tick, asking Buildbucket for the build status
[04:22:43.385] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:43.385] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:7:0) after 1m0s
[04:23:43.457] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:7:0)
[04:23:43.457] Timer tick, asking Buildbucket for the build status
[04:23:43.524] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:43.524] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:8:0) after 1m0s
[04:24:43.592] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:8:0)
[04:24:43.592] Timer tick, asking Buildbucket for the build status
[04:24:43.814] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:43.814] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:9:0) after 1m0s
[04:25:43.854] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:9:0)
[04:25:43.854] Timer tick, asking Buildbucket for the build status
[04:25:43.927] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:43.927] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:10:0) after 1m0s
[04:26:43.948] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:10:0)
[04:26:43.948] Timer tick, asking Buildbucket for the build status
[04:26:44.003] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:44.003] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:11:0) after 1m0s
[04:27:44.132] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:11:0)
[04:27:44.132] Timer tick, asking Buildbucket for the build status
[04:27:44.483] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:44.483] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:12:0) after 1m0s
[04:28:44.528] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:12:0)
[04:28:44.528] Timer tick, asking Buildbucket for the build status
[04:28:44.702] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:44.702] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:13:0) after 1m0s
[04:29:44.724] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:13:0)
[04:29:44.724] Timer tick, asking Buildbucket for the build status
[04:29:45.064] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:45.064] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:14:0) after 1m0s
[04:30:45.121] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:14:0)
[04:30:45.121] Timer tick, asking Buildbucket for the build status
[04:30:45.251] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:45.251] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:15:0) after 1m0s
[04:31:45.257] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:15:0)
[04:31:45.257] Timer tick, asking Buildbucket for the build status
[04:31:45.320] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:45.321] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:16:0) after 1m0s
[04:32:45.392] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:16:0)
[04:32:45.392] Timer tick, asking Buildbucket for the build status
[04:32:45.647] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:45.648] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:17:0) after 1m0s
[04:33:45.693] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:17:0)
[04:33:45.693] Timer tick, asking Buildbucket for the build status
[04:33:45.810] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:45.810] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:18:0) after 1m0s
[04:34:45.836] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:18:0)
[04:34:45.836] Timer tick, asking Buildbucket for the build status
[04:34:45.949] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:45.949] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:19:0) after 1m0s
[04:35:45.975] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:19:0)
[04:35:45.975] Timer tick, asking Buildbucket for the build status
[04:35:46.462] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:46.462] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:20:0) after 1m0s
[04:36:46.510] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:20:0)
[04:36:46.510] Timer tick, asking Buildbucket for the build status
[04:36:46.630] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:46.630] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:21:0) after 1m0s
[04:37:46.684] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:21:0)
[04:37:46.684] Timer tick, asking Buildbucket for the build status
[04:37:46.920] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:46.920] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:22:0) after 1m0s
[04:38:46.971] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:22:0)
[04:38:46.971] Timer tick, asking Buildbucket for the build status
[04:38:47.160] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:47.160] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:23:0) after 1m0s
[04:39:47.153] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:23:0)
[04:39:47.153] Timer tick, asking Buildbucket for the build status
[04:39:47.268] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:47.268] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:24:0) after 1m0s
[04:40:47.389] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:24:0)
[04:40:47.389] Timer tick, asking Buildbucket for the build status
[04:40:47.497] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:47.497] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:25:0) after 1m0s
[04:41:47.569] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:25:0)
[04:41:47.569] Timer tick, asking Buildbucket for the build status
[04:41:47.722] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:47.722] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:26:0) after 1m0s
[04:42:47.746] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:26:0)
[04:42:47.746] Timer tick, asking Buildbucket for the build status
[04:42:47.930] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:47.930] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:27:0) after 1m0s
[04:43:47.925] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:27:0)
[04:43:47.925] Timer tick, asking Buildbucket for the build status
[04:43:48.090] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:48.090] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:28:0) after 1m0s
[04:44:48.131] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:28:0)
[04:44:48.131] Timer tick, asking Buildbucket for the build status
[04:44:48.261] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:48.261] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:29:0) after 1m0s
[04:45:48.280] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:29:0)
[04:45:48.280] Timer tick, asking Buildbucket for the build status
[04:45:48.390] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:48.390] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:30:0) after 1m0s
[04:46:48.411] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:30:0)
[04:46:48.411] Timer tick, asking Buildbucket for the build status
[04:46:48.501] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:48.501] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:31:0) after 1m0s
[04:47:48.572] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:31:0)
[04:47:48.572] Timer tick, asking Buildbucket for the build status
[04:47:48.893] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:48.893] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:32:0) after 1m0s
[04:48:48.885] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:32:0)
[04:48:48.885] Timer tick, asking Buildbucket for the build status
[04:48:49.034] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:49.034] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:33:0) after 1m0s
[04:49:49.057] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:33:0)
[04:49:49.057] Timer tick, asking Buildbucket for the build status
[04:49:49.121] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:49.121] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:34:0) after 1m0s
[04:50:49.143] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:34:0)
[04:50:49.143] Timer tick, asking Buildbucket for the build status
[04:50:49.259] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:49.259] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:35:0) after 1m0s
[04:51:49.319] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:35:0)
[04:51:49.319] Timer tick, asking Buildbucket for the build status
[04:51:49.606] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:49.606] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:36:0) after 1m0s
[04:52:49.629] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:36:0)
[04:52:49.629] Timer tick, asking Buildbucket for the build status
[04:52:49.790] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:49.790] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:37:0) after 1m0s
[04:53:49.822] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:37:0)
[04:53:49.822] Timer tick, asking Buildbucket for the build status
[04:53:50.192] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:50.192] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:38:0) after 1m0s
[04:54:50.416] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:38:0)
[04:54:50.416] Timer tick, asking Buildbucket for the build status
[04:54:50.726] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:50.726] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:39:0) after 1m0s
[04:55:50.747] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:39:0)
[04:55:50.747] Timer tick, asking Buildbucket for the build status
[04:55:50.858] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:50.858] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:40:0) after 1m0s
[04:56:50.880] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:40:0)
[04:56:50.880] Timer tick, asking Buildbucket for the build status
[04:56:50.978] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:50.978] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:41:0) after 1m0s
[04:57:51.003] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:41:0)
[04:57:51.003] Timer tick, asking Buildbucket for the build status
[04:57:51.416] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:51.416] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:42:0) after 1m0s
[04:58:51.496] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:42:0)
[04:58:51.496] Timer tick, asking Buildbucket for the build status
[04:58:51.770] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:51.770] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:43:0) after 1m0s
[04:59:51.794] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:43:0)
[04:59:51.794] Timer tick, asking Buildbucket for the build status
[04:59:51.967] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:51.967] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:44:0) after 1m0s
[05:00:52.027] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:44:0)
[05:00:52.027] Timer tick, asking Buildbucket for the build status
[05:00:52.185] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:52.185] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:45:0) after 1m0s
[05:01:52.237] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:45:0)
[05:01:52.237] Timer tick, asking Buildbucket for the build status
[05:01:52.423] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:52.423] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:46:0) after 1m0s
[05:02:52.467] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:46:0)
[05:02:52.467] Timer tick, asking Buildbucket for the build status
[05:02:52.549] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:52.549] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:47:0) after 1m0s
[05:03:52.585] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:47:0)
[05:03:52.585] Timer tick, asking Buildbucket for the build status
[05:03:52.791] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:52.791] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:48:0) after 1m0s
[05:04:52.832] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:48:0)
[05:04:52.832] Timer tick, asking Buildbucket for the build status
[05:04:52.918] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:52.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:49:0) after 1m0s
[05:05:52.980] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:49:0)
[05:05:52.980] Timer tick, asking Buildbucket for the build status
[05:05:53.143] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:53.143] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:50:0) after 1m0s
[05:06:53.201] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:50:0)
[05:06:53.201] Timer tick, asking Buildbucket for the build status
[05:06:53.543] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:53.543] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:51:0) after 1m0s
[05:07:53.550] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:51:0)
[05:07:53.550] Timer tick, asking Buildbucket for the build status
[05:07:53.646] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:53.646] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:52:0) after 1m0s
[05:08:53.832] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:52:0)
[05:08:53.832] Timer tick, asking Buildbucket for the build status
[05:08:54.262] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:54.262] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:53:0) after 1m0s
[05:09:54.398] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:53:0)
[05:09:54.398] Timer tick, asking Buildbucket for the build status
[05:09:54.694] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:54.694] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:54:0) after 1m0s
[05:10:54.765] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:54:0)
[05:10:54.766] Timer tick, asking Buildbucket for the build status
[05:10:54.924] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:54.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:55:0) after 1m0s
[05:11:54.967] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:55:0)
[05:11:54.967] Timer tick, asking Buildbucket for the build status
[05:11:55.107] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:55.107] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:56:0) after 1m0s
[05:12:55.189] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:56:0)
[05:12:55.189] Timer tick, asking Buildbucket for the build status
[05:12:55.297] Build 8864772967710926224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:55.297] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030203030069705152:57:0) after 1m0s
[05:12:58.211] Received PubSub notification, asking Buildbucket for the build status
[05:12:58.483] Build 8864772967710926224: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:12:58.483] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1604293977252562",
"created_by": "project:chromium",
"created_ts": "1604290722129826",
"id": "8864772967710926224",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"fa808fd707ff7ef4c0da2040a2cec9eb3b712da2\"}}",
"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\": \"fa808fd707ff7ef4c0da2040a2cec9eb3b712da2\", \"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-us-central1-b-32-0-cki8\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15406, \"clang_revision\": \"llvmorg-12-init-5627-gf086e85e-2\", \"got_angle_revision\": \"068bb086e1c844161c18185a9110f9588f5b6e8a\", \"got_dawn_revision\": \"0a374dce665328391ab3b68fd9e667b018dd9dcd\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"fa808fd707ff7ef4c0da2040a2cec9eb3b712da2\", \"got_revision_cp\": \"refs/heads/master@{#823057}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"e0de282e678b72dac25b81f8d25305231aec01f8\", \"got_v8_revision\": \"69c856dc816ff1dd65fcc536095b44399a7434b9\", \"got_v8_revision_cp\": \"refs/heads/8.8.214@{#1}\", \"got_webrtc_revision\": \"ee24eb66d23ffea5ef729dc340e1fc4381c72a4a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32507}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"fa808fd707ff7ef4c0da2040a2cec9eb3b712da2\", \"swarm_hashes_refs/heads/master(at){#823057}_without_patch\": {\"absl_hardening_tests\": \"d5a86944f2a750fb455a9a2699620e68bdd97190\", \"accessibility_unittests\": \"2d86c8befae0ba61bc70f78388dd334f2770745c\", \"angle_unittests\": \"93d666cacde050d6d11937c7c9a60a4141874ae2\", \"app_shell_unittests\": \"87e6b24ea036993f828061e050b6cba6aa842b2f\", \"aura_unittests\": \"1dc39437a2f18a78f3ea6d50b837266b3102398a\", \"base_unittests\": \"dff8dc06b5c39f36d1f7122678558135b60c9fdf\", \"base_util_unittests\": \"5ebd7ae8e2bf642902d1e8348fb5b7c271f5c80e\", \"blink_common_unittests\": \"681453b8d4819c87c98b717c4497d24c8fb375f6\", \"blink_fuzzer_unittests\": \"fc0d0bb14f9ce4ca574d47666df53b5073fbe01f\", \"blink_heap_unittests\": \"04ae7edebbd9cef39b96a1485c040b4365a65b98\", \"blink_platform_unittests\": \"7c9b78ba8be6f038ab3ae803468f2845480c5fe3\", \"blink_unittests\": \"06d7e7e977cefa34a63648d9ad10a319d3036004\", \"boringssl_crypto_tests\": \"88cbfece12d9cb6fc9b8ac4db1e012d8775d31a2\", \"boringssl_ssl_tests\": \"bd1f7c2582a548a6144caa5ea57061db5d3159d5\", \"browser_switcher_bho_unittests\": \"1ed50446122794a2fa7b2c38f63f8972cffd5b8c\", \"browser_tests\": \"d3860d6ecdf5e36442b9a1b746cdc53d747f2642\", \"capture_unittests\": \"aa490649e285cfffd14d4b890ebae622511ac06e\", \"cast_unittests\": \"cb097d8c0bc37a3382382c66763ddcfda205ac7a\", \"cc_unittests\": \"f85c83a70728fa1d2f2810019056b1045744c615\", \"chrome_app_unittests\": \"1364fb346a6d0ef2804c4d77d872b3a77f186073\", \"chrome_cleaner_unittests\": \"8e4a21aa2a37845363fc3481aae3f36a376ef1dc\", \"chrome_elf_unittests\": \"3043cf36239e95fcaf1fa18430e4df87faf1d9cb\", \"chromedriver_unittests\": \"60014bd8b59a8a59ca8c989bd04f36951848249c\", \"components_browsertests\": \"4a3b1c787bfbb340969ff8d9061276949bc75e90\", \"components_unittests\": \"06cbf1a074848510336487f187242a38ccf28a76\", \"compositor_unittests\": \"4d6fd2a14b78b90c6663abd734c8be2434e102cc\", \"content_browsertests\": \"bce5474893721a8b3bd17ca6664af9da4afe9229\", \"content_unittests\": \"343cc07ba9cbf4b57f72f2482c6a888d87905892\", \"courgette_unittests\": \"7d13ee8c04571136cc677c437e6d5ca9b3b07f6f\", \"crashpad_tests\": \"7d50ff645dde37d0f12477bec3b0ebc2ff9c6556\", \"cronet_tests\": \"a787394c649a2c6d233c305dc868ae673d1ac9ab\", \"cronet_unittests\": \"0d6c2e06e990000f25a5cf122b8cd2ada4dd022f\", \"crypto_unittests\": \"6719b0c5b489d31b48daea1b5cdbee159cda404f\", \"delayloads_unittests\": \"0d85ff2a3c86150ed2e2ed304528b2006cadb6ff\", \"device_unittests\": \"674c666f563018469669271b12bdc6db72ea1548\", \"display_unittests\": \"48e36a521c81286942c8f880fef43a49e6ba4777\", \"elevation_service_unittests\": \"6c84163eb8e0a2b21685b77cc39a3ba37194d5f3\", \"events_unittests\": \"a725efeb6f2c2a80517021c12e6f76eeba0b6e1c\", \"extensions_browsertests\": \"6dab517b1e08b05d671d31c7414fcb007952d9c9\", \"extensions_unittests\": \"3ba1d718ee409707df2b9d4f28ac628c87692e2d\", \"filesystem_service_unittests\": \"4b2e8b3e4bd32caa7ef2ae35b06f476acb47be08\", \"gcm_unit_tests\": \"dcf8eac2f4512eb1eec8cc12fe1a4ec838e5d849\", \"gcp_unittests\": \"42fdce351b1a12a08f3599c4c3c6ac2fb6fc0e8f\", \"gfx_unittests\": \"63dd48003c0027cdae82a765f59f86ec0c0d1442\", \"gin_unittests\": \"423efac58365bece43917c4d0cfdb4ab20899643\", \"google_apis_unittests\": \"90f88c098d935c05c26193dca08ef64639bcd2c8\", \"gpu_unittests\": \"fca1483767ebe5b1c8f1b59a40be67c94df09982\", \"gwp_asan_unittests\": \"38a832f8d8d57c9f0f126591410bd172beca474d\", \"headless_browsertests\": \"b7fbf57539deb2a23a0783b1dafb04d5052e3c31\", \"headless_unittests\": \"633edcf194cf428f92a3a6c1e689513e01d85f93\", \"install_static_unittests\": \"0113705afce518735ebc94df605d91d1618dd705\", \"installer_util_unittests\": \"ba7b6473e683506e58981178215d3aaab9121b8d\", \"interactive_ui_tests\": \"b998e396a6c72fab43962b75e182054ee3176474\", \"ipc_tests\": \"4e88cf786df54c9c3a2effee60aa4029b34156b2\", \"jingle_unittests\": \"57c714b8670b394f1e1c58dbb846eea169089371\", \"latency_unittests\": \"ef1f17bcd8ebd0c5924c7e88ea86edcbbca89371\", \"libjingle_xmpp_unittests\": \"1b08636bc77c9f7b7932280b93f38f72c7560990\", \"liburlpattern_unittests\": \"9be2c250e7759e35a7fb76719aee63f52d108561\", \"media_blink_unittests\": \"d7689a1da6eaa2369c566f1831c9d4b27ed23af2\", \"media_unittests\": \"f2a42b6e4988f188149bd88d5ec4337bd78a7443\", \"message_center_unittests\": \"bae439cd7185ac83d4405504b144c2ef8ccb6236\", \"midi_unittests\": \"8113edd74d8b717c4f44d5e2f0a8860321330ee3\", \"mojo_core_unittests\": \"e48c911080a6f5f560be8c1afea807a97a9275b0\", \"mojo_unittests\": \"01a5e0c50378db8adf2de67357fcbdade9d558a6\", \"nacl_loader_unittests\": \"69e71b912683b988ac4c787cd388efeb9a8843da\", \"native_theme_unittests\": \"5733d8be35da31eb4d371c5520cb706e8e0bb722\", \"net_unittests\": \"f25a0ab68735471605bc807c379e10ade0cf2b3b\", \"notification_helper_unittests\": \"1326ea55bc515fc2b5b73fb1d7d3c69b6a5312da\", \"pdf_unittests\": \"f5554a66decccb7af9bf844e56112318b9597424\", \"perfetto_unittests\": \"c95d5f0a9c3aa8a18b77d8ad5835e9a6b6f25c35\", \"ppapi_unittests\": \"b72f9293ee9f958a581a34ac04f1ca0519798f52\", \"printing_unittests\": \"54129b379ebd3a5444baf5ebbe5547eac53744c3\", \"remoting_unittests\": \"cfe23d718993dae0e49d1817d7497b6d99c298fe\", \"sbox_integration_tests\": \"dbceea529608fd7babb570c35b15b1cbfb279d3c\", \"sbox_unittests\": \"b80b87758729672cffa9968eeb2c811496263cea\", \"sbox_validation_tests\": \"6769eb943f975f2910eab84bf63d18e826b46922\", \"service_manager_unittests\": \"29bfe9aa41a3261b27a27775b33b9c09dca79d6d\", \"services_unittests\": \"839ca0038b6d5c646629aeecb37e38a0001f015e\", \"setup_unittests\": \"9fa4f90bc9dc930655202d16dbbdb8685e482f05\", \"shell_dialogs_unittests\": \"e24b258da03ee84d75ffc1544467b00f9545c5e1\", \"skia_unittests\": \"d9e0b36aeb1a6d5f1866db2fd0fa7a74e78343b0\", \"snapshot_unittests\": \"86ad251a62eb29322d621a05d479fa74ddef24b7\", \"sql_unittests\": \"37c52cd19638482955dd59cf4266d5d6cdb033e9\", \"storage_unittests\": \"136b1c874bdc120dd852f9b7bfda09b8580742e8\", \"sync_integration_tests\": \"5e9cf0b4a01fa4884f264f16803acae8d3febb3f\", \"traffic_annotation_auditor_unittests\": \"50c79a81338d28720caf80b8586a632eca986d69\", \"ui_base_unittests\": \"ba03b46c9f6349024384b346b9705c3f462f27b3\", \"ui_touch_selection_unittests\": \"ec5b291cecba5a3e626a66672445c1304ab41bda\", \"unit_tests\": \"151547a1bb9b74d864254dcd28d50f0c6616a506\", \"updater_tests\": \"c38e1385ee73e85dadc0e45f0eaec00084dbf524\", \"url_unittests\": \"aadab304eace47ad3864218fef55f8fe0ab21943\", \"views_unittests\": \"85739a21922c32a2b46f4d0810f9027df778b8b8\", \"viz_unittests\": \"4e35cdb4407b6c32f0558218bdf2efdf5cdb67ff\", \"vr_common_unittests\": \"340c3fcb694d25b8861db33babc16832a164afde\", \"vr_pixeltests\": \"a2f94cb09229d469bf330c678c3a3f8c364c43d6\", \"weblayer_browsertests\": \"c3e163c016039f4143a40e498fa46f489868f9be\", \"weblayer_unittests\": \"a2c9696156e29c917471606749527bdc217e6d43\", \"wm_unittests\": \"3d8d8c8ce6bc54630ae4c6b2903572c5495aaf43\", \"wtf_unittests\": \"023fcc31aa4a272892a7794765ba8ab773727450\", \"zlib_unittests\": \"68dcde7ea2dc3f534e393188e9561c32ecd644c8\", \"zucchini_unittests\": \"944d862ac9b593887a7d63a508f6d5ea9e14c8e6\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_ba2591630e3c492a7f8bed019e3d41c3ae1f8879793a1bf71c3be1367a2696d6_v2\", \"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-us-central1-b-32-0-cki8\"], \"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\": [\"5614-d862eaa\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1604290723924336",
"status": "COMPLETED",
"status_changed_ts": "1604293977646330",
"tags": [
"build_address:luci.chromium.ci/win-asan/15406",
"builder:win-asan",
"buildset:commit/git/fa808fd707ff7ef4c0da2040a2cec9eb3b712da2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/fa808fd707ff7ef4c0da2040a2cec9eb3b712da2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030203030069705152",
"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/8864772967710926224/+/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:4f9fff45c99c1610",
"user_agent:luci-scheduler"
],
"updated_ts": "1604293977650550",
"url": "https://ci.chromium.org/b/8864772967710926224",
"utcnow_ts": "1604293978453794"
}
}
[05:12:58.483] Invocation finished in 54m17.987011337s with status SUCCEEDED