[14:36:36.198] New invocation is queued and will start shortly
[14:36:37.296] Starting the invocation (attempt 1)
[14:36:37.334] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:36:37.334] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:36:37.334] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9027899229236280256",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"249b1463ccacd1287cae43d96c48ecbbd35252e5\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9027899229236280256",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/249b1463ccacd1287cae43d96c48ecbbd35252e5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/249b1463ccacd1287cae43d96c48ecbbd35252e5",
"gitiles_ref:refs/heads/master"
]
}
[14:36:38.126] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1606487797417841",
"id": "8862469167293907056",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"249b1463ccacd1287cae43d96c48ecbbd35252e5\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1606487797942944",
"tags": [
"build_address:luci.chromium.ci/win-asan/15886",
"builder:win-asan",
"buildset:commit/git/249b1463ccacd1287cae43d96c48ecbbd35252e5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/249b1463ccacd1287cae43d96c48ecbbd35252e5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027899229236280256",
"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/8862469167293907056/+/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": "1606487797945919",
"url": "https://ci.chromium.org/b/8862469167293907056",
"utcnow_ts": "1606487798118662"
}
}
[14:36:38.126] Task URL: https://ci.chromium.org/b/8862469167293907056
[14:36:38.126] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:2:0) after 1m0s
[14:36:44.078] Received PubSub notification, asking Buildbucket for the build status
[14:36:44.220] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:38.104] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:2:0)
[14:37:38.104] Timer tick, asking Buildbucket for the build status
[14:37:38.266] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:38.266] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:4:0) after 1m0s
[14:38:38.289] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:4:0)
[14:38:38.289] Timer tick, asking Buildbucket for the build status
[14:38:38.381] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:38.381] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:5:0) after 1m0s
[14:39:38.403] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:5:0)
[14:39:38.403] Timer tick, asking Buildbucket for the build status
[14:39:38.536] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:38.537] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:6:0) after 1m0s
[14:40:38.558] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:6:0)
[14:40:38.559] Timer tick, asking Buildbucket for the build status
[14:40:38.661] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:38.661] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:7:0) after 1m0s
[14:41:38.681] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:7:0)
[14:41:38.681] Timer tick, asking Buildbucket for the build status
[14:41:38.762] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:38.762] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:8:0) after 1m0s
[14:42:38.782] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:8:0)
[14:42:38.782] Timer tick, asking Buildbucket for the build status
[14:42:39.029] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:39.029] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:9:0) after 1m0s
[14:43:39.097] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:9:0)
[14:43:39.097] Timer tick, asking Buildbucket for the build status
[14:43:39.163] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:39.163] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:10:0) after 1m0s
[14:44:39.216] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:10:0)
[14:44:39.216] Timer tick, asking Buildbucket for the build status
[14:44:39.592] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:39.592] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:11:0) after 1m0s
[14:45:39.613] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:11:0)
[14:45:39.613] Timer tick, asking Buildbucket for the build status
[14:45:39.662] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:39.662] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:12:0) after 1m0s
[14:46:39.668] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:12:0)
[14:46:39.668] Timer tick, asking Buildbucket for the build status
[14:46:40.319] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:40.319] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:13:0) after 1m0s
[14:47:40.372] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:13:0)
[14:47:40.372] Timer tick, asking Buildbucket for the build status
[14:47:40.442] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:40.442] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:14:0) after 1m0s
[14:48:40.470] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:14:0)
[14:48:40.470] Timer tick, asking Buildbucket for the build status
[14:48:40.566] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:40.566] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:15:0) after 1m0s
[14:49:40.547] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:15:0)
[14:49:40.547] Timer tick, asking Buildbucket for the build status
[14:49:40.825] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:40.825] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:16:0) after 1m0s
[14:50:40.880] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:16:0)
[14:50:40.880] Timer tick, asking Buildbucket for the build status
[14:50:40.937] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:40.937] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:17:0) after 1m0s
[14:51:40.940] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:17:0)
[14:51:40.940] Timer tick, asking Buildbucket for the build status
[14:51:41.058] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:41.058] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:18:0) after 1m0s
[14:52:41.150] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:18:0)
[14:52:41.150] Timer tick, asking Buildbucket for the build status
[14:52:41.280] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:41.280] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:19:0) after 1m0s
[14:53:41.332] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:19:0)
[14:53:41.332] Timer tick, asking Buildbucket for the build status
[14:53:41.490] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:41.490] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:20:0) after 1m0s
[14:54:41.563] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:20:0)
[14:54:41.563] Timer tick, asking Buildbucket for the build status
[14:54:41.743] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:41.743] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:21:0) after 1m0s
[14:55:41.781] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:21:0)
[14:55:41.781] Timer tick, asking Buildbucket for the build status
[14:55:41.922] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:41.922] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:22:0) after 1m0s
[14:56:41.977] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:22:0)
[14:56:41.977] Timer tick, asking Buildbucket for the build status
[14:56:42.163] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:42.163] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:23:0) after 1m0s
[14:57:42.197] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:23:0)
[14:57:42.197] Timer tick, asking Buildbucket for the build status
[14:57:42.316] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:42.316] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:24:0) after 1m0s
[14:58:42.339] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:24:0)
[14:58:42.339] Timer tick, asking Buildbucket for the build status
[14:58:42.463] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:42.463] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:25:0) after 1m0s
[14:59:42.514] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:25:0)
[14:59:42.514] Timer tick, asking Buildbucket for the build status
[14:59:42.631] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:42.631] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:26:0) after 1m0s
[15:00:42.661] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:26:0)
[15:00:42.661] Timer tick, asking Buildbucket for the build status
[15:00:42.837] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:42.837] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:27:0) after 1m0s
[15:01:42.893] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:27:0)
[15:01:42.893] Timer tick, asking Buildbucket for the build status
[15:01:43.071] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:43.071] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:28:0) after 1m0s
[15:02:43.074] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:28:0)
[15:02:43.074] Timer tick, asking Buildbucket for the build status
[15:02:43.270] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:43.270] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:29:0) after 1m0s
[15:03:43.301] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:29:0)
[15:03:43.301] Timer tick, asking Buildbucket for the build status
[15:03:43.427] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:43.427] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:30:0) after 1m0s
[15:04:43.522] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:30:0)
[15:04:43.522] Timer tick, asking Buildbucket for the build status
[15:04:43.943] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:43.943] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:31:0) after 1m0s
[15:05:43.969] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:31:0)
[15:05:43.969] Timer tick, asking Buildbucket for the build status
[15:05:44.121] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:44.121] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:32:0) after 1m0s
[15:06:44.143] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:32:0)
[15:06:44.144] Timer tick, asking Buildbucket for the build status
[15:06:44.338] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:44.338] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:33:0) after 1m0s
[15:07:44.356] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:33:0)
[15:07:44.356] Timer tick, asking Buildbucket for the build status
[15:07:44.780] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:44.780] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:34:0) after 1m0s
[15:08:44.802] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:34:0)
[15:08:44.802] Timer tick, asking Buildbucket for the build status
[15:08:44.951] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:44.951] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:35:0) after 1m0s
[15:09:44.975] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:35:0)
[15:09:44.975] Timer tick, asking Buildbucket for the build status
[15:09:46.148] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:46.148] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:36:0) after 1m0s
[15:10:46.136] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:36:0)
[15:10:46.136] Timer tick, asking Buildbucket for the build status
[15:10:46.307] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:46.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:37:0) after 1m0s
[15:11:46.371] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:37:0)
[15:11:46.371] Timer tick, asking Buildbucket for the build status
[15:11:46.528] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:46.529] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:38:0) after 1m0s
[15:12:46.734] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:38:0)
[15:12:46.734] Timer tick, asking Buildbucket for the build status
[15:12:46.820] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:46.820] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:39:0) after 1m0s
[15:13:46.990] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:39:0)
[15:13:46.990] Timer tick, asking Buildbucket for the build status
[15:13:47.158] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:47.158] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:40:0) after 1m0s
[15:14:47.236] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:40:0)
[15:14:47.236] Timer tick, asking Buildbucket for the build status
[15:14:47.344] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:47.344] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:41:0) after 1m0s
[15:15:47.472] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:41:0)
[15:15:47.472] Timer tick, asking Buildbucket for the build status
[15:15:47.646] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:47.646] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:42:0) after 1m0s
[15:16:47.739] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:42:0)
[15:16:47.739] Timer tick, asking Buildbucket for the build status
[15:16:47.898] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:47.898] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:43:0) after 1m0s
[15:17:47.869] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:43:0)
[15:17:47.869] Timer tick, asking Buildbucket for the build status
[15:17:48.333] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:48.333] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:44:0) after 1m0s
[15:18:48.524] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:44:0)
[15:18:48.524] Timer tick, asking Buildbucket for the build status
[15:18:48.719] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:48.719] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:45:0) after 1m0s
[15:19:48.758] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:45:0)
[15:19:48.758] Timer tick, asking Buildbucket for the build status
[15:19:48.900] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:48.900] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:46:0) after 1m0s
[15:20:48.921] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:46:0)
[15:20:48.921] Timer tick, asking Buildbucket for the build status
[15:20:49.306] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:49.306] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:47:0) after 1m0s
[15:21:49.334] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:47:0)
[15:21:49.334] Timer tick, asking Buildbucket for the build status
[15:21:50.305] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:50.305] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:48:0) after 1m0s
[15:22:50.407] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:48:0)
[15:22:50.407] Timer tick, asking Buildbucket for the build status
[15:22:52.112] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:52.112] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:49:0) after 1m0s
[15:23:52.137] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:49:0)
[15:23:52.137] Timer tick, asking Buildbucket for the build status
[15:23:52.211] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:52.211] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:50:0) after 1m0s
[15:24:52.260] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:50:0)
[15:24:52.260] Timer tick, asking Buildbucket for the build status
[15:24:52.341] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:52.341] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:51:0) after 1m0s
[15:25:52.505] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:51:0)
[15:25:52.505] Timer tick, asking Buildbucket for the build status
[15:25:52.621] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:52.621] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:52:0) after 1m0s
[15:26:52.656] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:52:0)
[15:26:52.656] Timer tick, asking Buildbucket for the build status
[15:26:52.837] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:52.837] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:53:0) after 1m0s
[15:27:52.984] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:53:0)
[15:27:52.984] Timer tick, asking Buildbucket for the build status
[15:27:53.060] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:53.060] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:54:0) after 1m0s
[15:28:53.211] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:54:0)
[15:28:53.211] Timer tick, asking Buildbucket for the build status
[15:28:53.278] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:53.278] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:55:0) after 1m0s
[15:29:53.494] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:55:0)
[15:29:53.494] Timer tick, asking Buildbucket for the build status
[15:29:53.994] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:53.994] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:56:0) after 1m0s
[15:30:54.077] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:56:0)
[15:30:54.077] Timer tick, asking Buildbucket for the build status
[15:30:54.193] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:54.193] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:57:0) after 1m0s
[15:31:54.272] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:57:0)
[15:31:54.272] Timer tick, asking Buildbucket for the build status
[15:31:54.429] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:54.429] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:58:0) after 1m0s
[15:32:54.436] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:58:0)
[15:32:54.436] Timer tick, asking Buildbucket for the build status
[15:32:54.566] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:54.566] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:59:0) after 1m0s
[15:33:54.664] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:59:0)
[15:33:54.664] Timer tick, asking Buildbucket for the build status
[15:33:54.782] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:54.782] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:60:0) after 1m0s
[15:34:54.805] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:60:0)
[15:34:54.805] Timer tick, asking Buildbucket for the build status
[15:34:54.955] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:54.955] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:61:0) after 1m0s
[15:35:55.060] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:61:0)
[15:35:55.060] Timer tick, asking Buildbucket for the build status
[15:35:55.187] Build 8862469167293907056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:55.187] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027899229236280256:62:0) after 1m0s
[15:36:46.874] Received PubSub notification, asking Buildbucket for the build status
[15:36:47.014] Build 8862469167293907056: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[15:36:47.014] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1606491406025358",
"created_by": "project:chromium",
"created_ts": "1606487797417841",
"id": "8862469167293907056",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"249b1463ccacd1287cae43d96c48ecbbd35252e5\"}}",
"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\": \"249b1463ccacd1287cae43d96c48ecbbd35252e5\", \"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-4-qhsy\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15886, \"clang_revision\": \"llvmorg-12-init-12083-g94e4ec64-1\", \"got_angle_revision\": \"96b71bee13a9281e303311e46711dbd30e9046ed\", \"got_dawn_revision\": \"4814bdbdeae40186a61dfb7494390e64820daa4f\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"249b1463ccacd1287cae43d96c48ecbbd35252e5\", \"got_revision_cp\": \"refs/heads/master@{#831568}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"9dff6a3bb5950edd890e5141f2d3d2f3b4ef351e\", \"got_v8_revision\": \"961f93287f84e7e7a7f5ff8a43c7adf14ca7fc42\", \"got_v8_revision_cp\": \"refs/heads/8.9.63@{#1}\", \"got_webrtc_revision\": \"0d863f72a8c747c1b41f2798e5201e1abcdaec2b\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32704}\", \"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\": \"249b1463ccacd1287cae43d96c48ecbbd35252e5\", \"swarm_hashes_refs/heads/master(at){#831568}_without_patch\": {\"absl_hardening_tests\": \"82aa67fbb5885bc90653b5daa2d0c7c71f6c2dba\", \"accessibility_unittests\": \"8fcb6336c70dadf064216317d7d5db22f0fd143a\", \"angle_unittests\": \"2c2fb8df55224a220fb51438ec57d4c27f5f4e40\", \"app_shell_unittests\": \"927e994a445de8022851495d28deb773c32b2ce6\", \"aura_unittests\": \"cb9e3a2549af5cc4fce6c918bca510ea60d322bf\", \"base_unittests\": \"849c5a28d75a0f137608467f4f6b8db9218126b0\", \"base_util_unittests\": \"22dae98eec4f8488849be3196607efa28f5e429e\", \"blink_common_unittests\": \"b2344ae6e1505fb5dcf4ab970afc69d81692117e\", \"blink_fuzzer_unittests\": \"ef456ab195437999db5e9d01a41d61b42ebee4d1\", \"blink_heap_unittests\": \"72e7d0ede799b0b271280a1d3a5bf566efc03c0b\", \"blink_platform_unittests\": \"8d0e925ff522d739340b8c93804000da2af14eb5\", \"blink_unittests\": \"9a8ab5c4265b99df716909a15dd3ff50f619dc4c\", \"boringssl_crypto_tests\": \"70879457f1fc7b8ba0f90aabe885b4877537f0c8\", \"boringssl_ssl_tests\": \"74d4cfac1c832b14ffe6dd5695a476b5a4421b09\", \"browser_switcher_bho_unittests\": \"af49d9ab209cf4b3b436608eb48c48acd746b828\", \"browser_tests\": \"67a409e18f453b4b4589b4ff8cec0e4cd6bc82e4\", \"capture_unittests\": \"33d449f2ac5f7e7f2cf1c1c63b53129689286cb6\", \"cast_unittests\": \"c8923d53ecf0ef26187dd989a6fd4c73efb644e5\", \"cc_unittests\": \"a2944d3f4a3893f4a2b50178a50ce28d46bdade6\", \"chrome_app_unittests\": \"9d8c52b342946bf529cd2896188b1613e5831412\", \"chrome_cleaner_unittests\": \"ac4646318ad529e31e3e053efd19a61ebe062031\", \"chrome_elf_unittests\": \"a4daf32e3aea16e4f83813ff2a84975a45ba1c62\", \"chromedriver_unittests\": \"a6c39e4d6ccbc210b5ca7d29382c120e44b29121\", \"components_browsertests\": \"368f586eaa554106751b47d1285c5d86e355aa3a\", \"components_unittests\": \"18d4d00935abcb26c7c9b61c288fda9aac7a3112\", \"compositor_unittests\": \"c56335d09a3f70170bba85912f9f184c6b4d9023\", \"content_browsertests\": \"81f1c3e1dbc36ac11670b946e732b103a7602869\", \"content_unittests\": \"8c8acc4119848343612b6922faba578e1ff8eef3\", \"courgette_unittests\": \"73e4c0e4357ffe6f9c0bda3b1aa8053c4a350d1e\", \"crashpad_tests\": \"730d05a025601d65d9be64c43a90dd56b9896ff9\", \"cronet_tests\": \"ddb876f47e0d13807052ceaa72be2f2e2050cf17\", \"cronet_unittests\": \"593663189d056d33927ab2d85601943ed37c3ae6\", \"crypto_unittests\": \"1542c0096b5a673dafa8cdbdde8605ab40d8c7b6\", \"delayloads_unittests\": \"7c37bdc212590fbde898afc743667c0faf530dc9\", \"device_unittests\": \"95b4634544ed8af9f2cac0d471e8c07fc2d38a90\", \"display_unittests\": \"058c8b15b3b9c3f222bdc8e8131e4185040aaa56\", \"elevation_service_unittests\": \"5e6d13882228284f94173c7c394586698198f889\", \"events_unittests\": \"14e868c1876299ecd4760b3bb87493ff33986e10\", \"extensions_browsertests\": \"46dbf387356e23486effae0ad2855b63da0bca17\", \"extensions_unittests\": \"0372d7adc318bccf605cc6558d4e35eafe11fbaf\", \"filesystem_service_unittests\": \"2b9d6174380df17284c3b6097638632de7184cfe\", \"gcm_unit_tests\": \"aec48865315415ba7f39a3649af3c37abfd8c922\", \"gcp_unittests\": \"67d1cc8e04250da5c9c6316c8eafc3736fb2cf44\", \"gfx_unittests\": \"30efce4f5c0d89a3caf2bf752a1095596981973b\", \"gin_unittests\": \"3dc30a3c64ea7d81fe64ca77340b5d19e055ac8f\", \"google_apis_unittests\": \"7f580c9662dcfef2aa7557f983894dbb8141f12e\", \"gpu_unittests\": \"18b1d665abfcc5457735f31c7b74b66a006f4c7c\", \"gwp_asan_unittests\": \"fb9e8672dd9392410d8f62def0ac7de9cbe49b04\", \"headless_browsertests\": \"acfa72712a07c3c02b073825c01d1033f38fac3c\", \"headless_unittests\": \"80abe2e8d3ac3a926553b875b2626faf87432d6c\", \"install_static_unittests\": \"c5eeb68b34873c43bc8bbf57ddd1b19a4bb4960e\", \"installer_util_unittests\": \"ebcb5c1d66dabca3163538fc59fa5398e88840cc\", \"interactive_ui_tests\": \"1bc17385745508ae19307bc2ef5a63d1a20263c5\", \"ipc_tests\": \"d03d6ab83a72a59c5c25227c60f6ecf71128d17d\", \"jingle_unittests\": \"28b582804d965550407e164d1f5f75f777b03dd8\", \"latency_unittests\": \"0f2c11751c53ea8815a7f052cb9c2947e14a3437\", \"libjingle_xmpp_unittests\": \"0a84aa47aa771588717a81acc4f0a9e5a7746252\", \"liburlpattern_unittests\": \"fd05ee5b31addaf5047a0e010bb9f6a5a3b1f3d0\", \"media_blink_unittests\": \"78b3a39dacf2f27e06bc12c8b1667dc74db55394\", \"media_unittests\": \"44f05422ed7031310d473898555ffe4384a85beb\", \"message_center_unittests\": \"ecd8d68f09e487675ae2533b8902aa9283285840\", \"midi_unittests\": \"426849819e62b467ba935f7b7b40f4fd5a570f51\", \"mojo_core_unittests\": \"5cd7d119197acac3bc5028d8c948ade749d80ab3\", \"mojo_unittests\": \"aa00df7068b3e80d9cc10a83935feaddfc5bbf1f\", \"nacl_loader_unittests\": \"68c647e2ed9bc1993be9111efc6023c430f0fe53\", \"native_theme_unittests\": \"40a691e4645edadd19fae49adad97fc22dcaea23\", \"net_unittests\": \"33283739afd3189ec4168a2fe5d0411279585267\", \"notification_helper_unittests\": \"1f5df88702ed91d1f929dd546a3457ff3939bddc\", \"pdf_unittests\": \"61114edd3f9c182bc141df1ff837e56ad1573cb0\", \"perfetto_unittests\": \"f7bed5bba44cdf165d662b7e0342643e9cd3d2f5\", \"ppapi_unittests\": \"a30b66b944a759ce6cea826bb33904426db9fc8a\", \"printing_unittests\": \"2d933ec634a56d883604b25a1090068732b9a728\", \"remoting_unittests\": \"037114bb0c443c84f21dec5af2f4e27d38d5f609\", \"sbox_integration_tests\": \"0823695b4ba828c8c3dcf040649143cd5ee72128\", \"sbox_unittests\": \"d25055c36e66b930aa4d72e0df5e1f84b37a68c9\", \"sbox_validation_tests\": \"a00ad8915b9b94bc6871d3347749ea8accaeb5b0\", \"service_manager_unittests\": \"d1089d553f624e5fe4e28cb802b5332d398385c3\", \"services_unittests\": \"78f136ffc577d1b4dc3226492c61178985ec7143\", \"setup_unittests\": \"8e0a29eb0ba533a6908ec2b045e8f5277d08407c\", \"shell_dialogs_unittests\": \"041a7b6b41f74dc0eaebf4ff597509fd30ca36f7\", \"skia_unittests\": \"29f1156f0770ea92fad7a9492b23dca5b58b23bd\", \"snapshot_unittests\": \"81445dbc35d0d1f5d69411f5c894aa75f4ff4aaa\", \"sql_unittests\": \"190498fc256713750ba125c38236710735059915\", \"storage_unittests\": \"dad9efab733b084d7b9f7872678d4a0592f77719\", \"sync_integration_tests\": \"40180a2e808f94d1a3c95f38457e1da7b84c8841\", \"traffic_annotation_auditor_unittests\": \"a751bf21a7ac72ffb81ddb3f72ee3a8378f44cba\", \"ui_base_unittests\": \"e1a133b571df97b94562952fe7dc02a31872acb3\", \"ui_touch_selection_unittests\": \"ae7ad722896720730b71bc60e37a42e15523521b\", \"unit_tests\": \"bd14b886b0137158bfd8822f41726b045c11b1ae\", \"updater_tests\": \"b93dc699ad9482351aa089384bbfd096e88715dd\", \"url_unittests\": \"eea1aba62a0a470fe61554f4564fabf9ee541bc3\", \"views_unittests\": \"ba2eb5ebf9d624933d8dcf2879c7b9637c131c1c\", \"viz_unittests\": \"6612ed7842dce0bb748a9c570d6cb7801563fb51\", \"vr_common_unittests\": \"3d844dc50e053a885dc45d2f27c3dd5cce017541\", \"vr_pixeltests\": \"f20c6e7f3e7988ee68171a55df0802f28293b687\", \"weblayer_browsertests\": \"9c0cffd6bdeec44ba9c3d5bda58657a58f93387c\", \"weblayer_unittests\": \"370660b386bc627120083923324a7ca0ca1b975d\", \"wm_unittests\": \"1821cb6120ee11d895538b2d972c438d0e7c8a2d\", \"wtf_unittests\": \"ff4583f76eab549eedf12a12a9410c8c5d22347e\", \"zlib_unittests\": \"827cd81e434ea291b3382deacf14a071d3870896\", \"zucchini_unittests\": \"5fc74bbf6409902855f80c97fb4a1f73fc22a570\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_64c7a62a02e0da5ef76200bb7658267e40edf0984fe7c0f995e5999242969052_v2\", \"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-4-qhsy\"], \"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\": [\"5640-d0eb544\"], \"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": "1606487801699660",
"status": "COMPLETED",
"status_changed_ts": "1606491406405706",
"tags": [
"build_address:luci.chromium.ci/win-asan/15886",
"builder:win-asan",
"buildset:commit/git/249b1463ccacd1287cae43d96c48ecbbd35252e5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/249b1463ccacd1287cae43d96c48ecbbd35252e5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027899229236280256",
"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/8862469167293907056/+/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:5022f3f9abcb1010",
"user_agent:luci-scheduler"
],
"updated_ts": "1606491406418690",
"url": "https://ci.chromium.org/b/8862469167293907056",
"utcnow_ts": "1606491406999242"
}
}
[15:36:47.014] Invocation finished in 1h0m10.832691068s with status SUCCEEDED