[20:56:04.157] New invocation is queued and will start shortly
[20:56:05.264] Starting the invocation (attempt 1)
[20:56:05.308] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:56:05.308] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:56:05.308] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019359240457351024",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"e0fdf1c43eede1d9519303e638c34792a25faf9d\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9019359240457351024",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/e0fdf1c43eede1d9519303e638c34792a25faf9d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e0fdf1c43eede1d9519303e638c34792a25faf9d",
"gitiles_ref:refs/heads/master"
]
}
[20:56:06.141] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614632165383261",
"id": "8853929178509875440",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e0fdf1c43eede1d9519303e638c34792a25faf9d\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614632165948739",
"tags": [
"build_address:luci.chromium.ci/win-asan/17580",
"builder:win-asan",
"buildset:commit/git/e0fdf1c43eede1d9519303e638c34792a25faf9d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e0fdf1c43eede1d9519303e638c34792a25faf9d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019359240457351024",
"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/8853929178509875440/+/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": "1614632165948887",
"url": "https://ci.chromium.org/b/8853929178509875440",
"utcnow_ts": "1614632166131321"
}
}
[20:56:06.141] Task URL: https://ci.chromium.org/b/8853929178509875440
[20:56:06.141] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:2:0) after 1m0s
[20:57:06.433] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:2:0)
[20:57:06.433] Timer tick, asking Buildbucket for the build status
[20:57:06.526] Build 8853929178509875440: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:57:06.526] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:3:0) after 1m0s
[20:58:07.043] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:3:0)
[20:58:07.043] Timer tick, asking Buildbucket for the build status
[20:58:07.120] Build 8853929178509875440: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:58:07.120] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:4:0) after 1m0s
[20:59:07.356] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:4:0)
[20:59:07.356] Timer tick, asking Buildbucket for the build status
[20:59:07.411] Build 8853929178509875440: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:59:07.411] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:5:0) after 1m0s
[21:00:07.541] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:5:0)
[21:00:07.541] Timer tick, asking Buildbucket for the build status
[21:00:07.724] Build 8853929178509875440: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:00:07.724] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:6:0) after 1m0s
[21:00:16.770] Received PubSub notification, asking Buildbucket for the build status
[21:00:16.952] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:07.836] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:6:0)
[21:01:07.836] Timer tick, asking Buildbucket for the build status
[21:01:07.972] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:07.972] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:8:0) after 1m0s
[21:02:08.149] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:8:0)
[21:02:08.149] Timer tick, asking Buildbucket for the build status
[21:02:08.496] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:08.496] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:9:0) after 1m0s
[21:03:08.586] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:9:0)
[21:03:08.586] Timer tick, asking Buildbucket for the build status
[21:03:08.671] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:08.671] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:10:0) after 1m0s
[21:04:08.731] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:10:0)
[21:04:08.731] Timer tick, asking Buildbucket for the build status
[21:04:08.795] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:08.795] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:11:0) after 1m0s
[21:05:08.804] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:11:0)
[21:05:08.804] Timer tick, asking Buildbucket for the build status
[21:05:08.877] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:08.877] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:12:0) after 1m0s
[21:06:08.938] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:12:0)
[21:06:08.938] Timer tick, asking Buildbucket for the build status
[21:06:09.051] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:09.051] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:13:0) after 1m0s
[21:07:09.048] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:13:0)
[21:07:09.048] Timer tick, asking Buildbucket for the build status
[21:07:09.146] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:09.146] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:14:0) after 1m0s
[21:08:09.139] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:14:0)
[21:08:09.139] Timer tick, asking Buildbucket for the build status
[21:08:09.569] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:09.569] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:15:0) after 1m0s
[21:09:09.585] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:15:0)
[21:09:09.585] Timer tick, asking Buildbucket for the build status
[21:09:09.656] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:09.656] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:16:0) after 1m0s
[21:10:09.662] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:16:0)
[21:10:09.662] Timer tick, asking Buildbucket for the build status
[21:10:09.728] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:09.728] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:17:0) after 1m0s
[21:11:09.746] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:17:0)
[21:11:09.746] Timer tick, asking Buildbucket for the build status
[21:11:09.842] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:09.842] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:18:0) after 1m0s
[21:12:09.867] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:18:0)
[21:12:09.867] Timer tick, asking Buildbucket for the build status
[21:12:09.933] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:09.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:19:0) after 1m0s
[21:13:10.015] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:19:0)
[21:13:10.015] Timer tick, asking Buildbucket for the build status
[21:13:10.129] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:10.129] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:20:0) after 1m0s
[21:14:10.402] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:20:0)
[21:14:10.402] Timer tick, asking Buildbucket for the build status
[21:14:10.483] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:10.483] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:21:0) after 1m0s
[21:15:10.584] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:21:0)
[21:15:10.584] Timer tick, asking Buildbucket for the build status
[21:15:10.852] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:10.852] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:22:0) after 1m0s
[21:16:11.128] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:22:0)
[21:16:11.128] Timer tick, asking Buildbucket for the build status
[21:16:11.218] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:11.218] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:23:0) after 1m0s
[21:17:11.324] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:23:0)
[21:17:11.324] Timer tick, asking Buildbucket for the build status
[21:17:11.398] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:11.398] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:24:0) after 1m0s
[21:18:11.492] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:24:0)
[21:18:11.492] Timer tick, asking Buildbucket for the build status
[21:18:11.582] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:11.582] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:25:0) after 1m0s
[21:19:11.671] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:25:0)
[21:19:11.671] Timer tick, asking Buildbucket for the build status
[21:19:12.120] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:12.120] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:26:0) after 1m0s
[21:20:12.122] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:26:0)
[21:20:12.122] Timer tick, asking Buildbucket for the build status
[21:20:12.199] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:12.199] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:27:0) after 1m0s
[21:21:12.576] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:27:0)
[21:21:12.576] Timer tick, asking Buildbucket for the build status
[21:21:12.649] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:12.649] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:28:0) after 1m0s
[21:22:13.011] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:28:0)
[21:22:13.011] Timer tick, asking Buildbucket for the build status
[21:22:13.087] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:13.087] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:29:0) after 1m0s
[21:23:13.436] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:29:0)
[21:23:13.436] Timer tick, asking Buildbucket for the build status
[21:23:13.749] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:13.749] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:30:0) after 1m0s
[21:24:13.847] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:30:0)
[21:24:13.847] Timer tick, asking Buildbucket for the build status
[21:24:14.061] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:14.061] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:31:0) after 1m0s
[21:25:14.385] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:31:0)
[21:25:14.385] Timer tick, asking Buildbucket for the build status
[21:25:14.456] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:14.456] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:32:0) after 1m0s
[21:26:14.937] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:32:0)
[21:26:14.937] Timer tick, asking Buildbucket for the build status
[21:26:15.013] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:15.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:33:0) after 1m0s
[21:27:15.201] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:33:0)
[21:27:15.201] Timer tick, asking Buildbucket for the build status
[21:27:15.268] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:15.268] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:34:0) after 1m0s
[21:28:15.375] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:34:0)
[21:28:15.375] Timer tick, asking Buildbucket for the build status
[21:28:15.471] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:15.471] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:35:0) after 1m0s
[21:29:15.750] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:35:0)
[21:29:15.750] Timer tick, asking Buildbucket for the build status
[21:29:16.236] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:16.236] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:36:0) after 1m0s
[21:30:16.275] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:36:0)
[21:30:16.275] Timer tick, asking Buildbucket for the build status
[21:30:19.202] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:19.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:37:0) after 1m0s
[21:31:19.239] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:37:0)
[21:31:19.239] Timer tick, asking Buildbucket for the build status
[21:31:19.318] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:19.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:38:0) after 1m0s
[21:32:19.782] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:38:0)
[21:32:19.782] Timer tick, asking Buildbucket for the build status
[21:32:19.878] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:19.878] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:39:0) after 1m0s
[21:33:19.922] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:39:0)
[21:33:19.922] Timer tick, asking Buildbucket for the build status
[21:33:20.406] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:20.406] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:40:0) after 1m0s
[21:34:20.533] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:40:0)
[21:34:20.533] Timer tick, asking Buildbucket for the build status
[21:34:20.770] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:20.770] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:41:0) after 1m0s
[21:35:20.789] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:41:0)
[21:35:20.789] Timer tick, asking Buildbucket for the build status
[21:35:20.909] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:20.909] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:42:0) after 1m0s
[21:36:21.051] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:42:0)
[21:36:21.051] Timer tick, asking Buildbucket for the build status
[21:36:21.139] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:21.139] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:43:0) after 1m0s
[21:37:21.253] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:43:0)
[21:37:21.253] Timer tick, asking Buildbucket for the build status
[21:37:21.349] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:21.349] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:44:0) after 1m0s
[21:38:21.370] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:44:0)
[21:38:21.370] Timer tick, asking Buildbucket for the build status
[21:38:21.466] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:21.466] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:45:0) after 1m0s
[21:39:21.484] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:45:0)
[21:39:21.484] Timer tick, asking Buildbucket for the build status
[21:39:21.678] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:21.678] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:46:0) after 1m0s
[21:40:21.777] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:46:0)
[21:40:21.777] Timer tick, asking Buildbucket for the build status
[21:40:21.922] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:21.922] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:47:0) after 1m0s
[21:41:21.990] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:47:0)
[21:41:21.990] Timer tick, asking Buildbucket for the build status
[21:41:22.074] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:22.074] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:48:0) after 1m0s
[21:42:22.094] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:48:0)
[21:42:22.094] Timer tick, asking Buildbucket for the build status
[21:42:22.187] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:22.187] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:49:0) after 1m0s
[21:43:22.507] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:49:0)
[21:43:22.507] Timer tick, asking Buildbucket for the build status
[21:43:22.638] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:22.638] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:50:0) after 1m0s
[21:44:22.885] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:50:0)
[21:44:22.885] Timer tick, asking Buildbucket for the build status
[21:44:22.965] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:22.965] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:51:0) after 1m0s
[21:45:23.025] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:51:0)
[21:45:23.025] Timer tick, asking Buildbucket for the build status
[21:45:23.128] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:23.128] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:52:0) after 1m0s
[21:46:23.721] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:52:0)
[21:46:23.721] Timer tick, asking Buildbucket for the build status
[21:46:23.797] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:23.797] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:53:0) after 1m0s
[21:47:23.928] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:53:0)
[21:47:23.928] Timer tick, asking Buildbucket for the build status
[21:47:24.057] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:24.057] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:54:0) after 1m0s
[21:48:24.328] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:54:0)
[21:48:24.328] Timer tick, asking Buildbucket for the build status
[21:48:24.393] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:24.393] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:55:0) after 1m0s
[21:49:24.413] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:55:0)
[21:49:24.413] Timer tick, asking Buildbucket for the build status
[21:49:24.509] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:24.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:56:0) after 1m0s
[21:50:24.555] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:56:0)
[21:50:24.555] Timer tick, asking Buildbucket for the build status
[21:50:24.655] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:24.655] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:57:0) after 1m0s
[21:51:24.709] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:57:0)
[21:51:24.709] Timer tick, asking Buildbucket for the build status
[21:51:24.792] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:24.792] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:58:0) after 1m0s
[21:52:24.792] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:58:0)
[21:52:24.792] Timer tick, asking Buildbucket for the build status
[21:52:24.861] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:24.861] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:59:0) after 1m0s
[21:53:24.946] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:59:0)
[21:53:24.946] Timer tick, asking Buildbucket for the build status
[21:53:25.122] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:25.122] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:60:0) after 1m0s
[21:54:25.217] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:60:0)
[21:54:25.217] Timer tick, asking Buildbucket for the build status
[21:54:25.357] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:25.357] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:61:0) after 1m0s
[21:55:25.448] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:61:0)
[21:55:25.448] Timer tick, asking Buildbucket for the build status
[21:55:25.512] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:25.512] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:62:0) after 1m0s
[21:56:25.662] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:62:0)
[21:56:25.662] Timer tick, asking Buildbucket for the build status
[21:56:25.809] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:25.809] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:63:0) after 1m0s
[21:57:25.856] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:63:0)
[21:57:25.856] Timer tick, asking Buildbucket for the build status
[21:57:25.942] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:25.942] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:64:0) after 1m0s
[21:58:25.985] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:64:0)
[21:58:25.985] Timer tick, asking Buildbucket for the build status
[21:58:26.259] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:26.259] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:65:0) after 1m0s
[21:59:26.295] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:65:0)
[21:59:26.295] Timer tick, asking Buildbucket for the build status
[21:59:26.370] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:26.370] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:66:0) after 1m0s
[22:00:26.398] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:66:0)
[22:00:26.398] Timer tick, asking Buildbucket for the build status
[22:00:26.510] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:26.510] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:67:0) after 1m0s
[22:01:26.538] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:67:0)
[22:01:26.538] Timer tick, asking Buildbucket for the build status
[22:01:26.619] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:26.619] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:68:0) after 1m0s
[22:02:26.694] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:68:0)
[22:02:26.694] Timer tick, asking Buildbucket for the build status
[22:02:26.794] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:26.794] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:69:0) after 1m0s
[22:03:27.054] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:69:0)
[22:03:27.054] Timer tick, asking Buildbucket for the build status
[22:03:27.173] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:27.173] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:70:0) after 1m0s
[22:04:27.226] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:70:0)
[22:04:27.226] Timer tick, asking Buildbucket for the build status
[22:04:27.320] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:27.320] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:71:0) after 1m0s
[22:05:27.686] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:71:0)
[22:05:27.686] Timer tick, asking Buildbucket for the build status
[22:05:27.802] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:27.802] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:72:0) after 1m0s
[22:06:27.933] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:72:0)
[22:06:27.933] Timer tick, asking Buildbucket for the build status
[22:06:28.484] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:28.484] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:73:0) after 1m0s
[22:07:28.694] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:73:0)
[22:07:28.694] Timer tick, asking Buildbucket for the build status
[22:07:28.785] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:28.785] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:74:0) after 1m0s
[22:08:28.780] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:74:0)
[22:08:28.780] Timer tick, asking Buildbucket for the build status
[22:08:28.859] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:28.859] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:75:0) after 1m0s
[22:09:28.944] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:75:0)
[22:09:28.944] Timer tick, asking Buildbucket for the build status
[22:09:29.069] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:29.069] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:76:0) after 1m0s
[22:10:29.220] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:76:0)
[22:10:29.220] Timer tick, asking Buildbucket for the build status
[22:10:29.314] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:29.314] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:77:0) after 1m0s
[22:11:29.401] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:77:0)
[22:11:29.401] Timer tick, asking Buildbucket for the build status
[22:11:29.517] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:29.517] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:78:0) after 1m0s
[22:12:29.622] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:78:0)
[22:12:29.622] Timer tick, asking Buildbucket for the build status
[22:12:29.719] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:29.719] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:79:0) after 1m0s
[22:13:29.727] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:79:0)
[22:13:29.727] Timer tick, asking Buildbucket for the build status
[22:13:29.819] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:29.819] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:80:0) after 1m0s
[22:14:30.013] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:80:0)
[22:14:30.013] Timer tick, asking Buildbucket for the build status
[22:14:31.939] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:31.939] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:81:0) after 1m0s
[22:15:31.953] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:81:0)
[22:15:31.953] Timer tick, asking Buildbucket for the build status
[22:15:32.077] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:32.077] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:82:0) after 1m0s
[22:16:32.156] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:82:0)
[22:16:32.156] Timer tick, asking Buildbucket for the build status
[22:16:32.227] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:32.227] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:83:0) after 1m0s
[22:17:32.243] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:83:0)
[22:17:32.243] Timer tick, asking Buildbucket for the build status
[22:17:32.546] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:32.546] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:84:0) after 1m0s
[22:18:32.582] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:84:0)
[22:18:32.582] Timer tick, asking Buildbucket for the build status
[22:18:32.658] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:32.658] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:85:0) after 1m0s
[22:19:32.693] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:85:0)
[22:19:32.693] Timer tick, asking Buildbucket for the build status
[22:19:32.770] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:32.770] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:86:0) after 1m0s
[22:20:34.471] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:86:0)
[22:20:34.471] Timer tick, asking Buildbucket for the build status
[22:20:34.548] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:34.548] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:87:0) after 1m0s
[22:21:34.567] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:87:0)
[22:21:34.567] Timer tick, asking Buildbucket for the build status
[22:21:34.762] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:34.762] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:88:0) after 1m0s
[22:22:34.797] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:88:0)
[22:22:34.797] Timer tick, asking Buildbucket for the build status
[22:22:34.898] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:34.898] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:89:0) after 1m0s
[22:23:35.369] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:89:0)
[22:23:35.369] Timer tick, asking Buildbucket for the build status
[22:23:35.458] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:35.458] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:90:0) after 1m0s
[22:24:35.548] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:90:0)
[22:24:35.548] Timer tick, asking Buildbucket for the build status
[22:24:36.353] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:36.353] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:91:0) after 1m0s
[22:25:36.339] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:91:0)
[22:25:36.339] Timer tick, asking Buildbucket for the build status
[22:25:36.446] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:36.446] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:92:0) after 1m0s
[22:26:36.467] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:92:0)
[22:26:36.467] Timer tick, asking Buildbucket for the build status
[22:26:36.562] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:36.562] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:93:0) after 1m0s
[22:27:36.591] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:93:0)
[22:27:36.591] Timer tick, asking Buildbucket for the build status
[22:27:36.682] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:36.682] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:94:0) after 1m0s
[22:28:36.702] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:94:0)
[22:28:36.702] Timer tick, asking Buildbucket for the build status
[22:28:36.967] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:36.967] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:95:0) after 1m0s
[22:29:38.062] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:95:0)
[22:29:38.062] Timer tick, asking Buildbucket for the build status
[22:29:38.173] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:38.173] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:96:0) after 1m0s
[22:30:38.293] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:96:0)
[22:30:38.293] Timer tick, asking Buildbucket for the build status
[22:30:38.430] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:38.430] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:97:0) after 1m0s
[22:31:38.671] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:97:0)
[22:31:38.671] Timer tick, asking Buildbucket for the build status
[22:31:38.780] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:38.780] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:98:0) after 1m0s
[22:32:40.428] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:98:0)
[22:32:40.428] Timer tick, asking Buildbucket for the build status
[22:32:40.550] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:40.550] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:99:0) after 1m0s
[22:33:41.020] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:99:0)
[22:33:41.020] Timer tick, asking Buildbucket for the build status
[22:33:41.155] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:41.155] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:100:0) after 1m0s
[22:34:42.263] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:100:0)
[22:34:42.263] Timer tick, asking Buildbucket for the build status
[22:34:42.429] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:42.429] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:101:0) after 1m0s
[22:35:42.564] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:101:0)
[22:35:42.564] Timer tick, asking Buildbucket for the build status
[22:35:42.653] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:42.653] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:102:0) after 1m0s
[22:36:42.691] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:102:0)
[22:36:42.691] Timer tick, asking Buildbucket for the build status
[22:36:42.788] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:42.788] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:103:0) after 1m0s
[22:37:42.823] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:103:0)
[22:37:42.823] Timer tick, asking Buildbucket for the build status
[22:37:42.919] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:42.919] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:104:0) after 1m0s
[22:38:42.910] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:104:0)
[22:38:42.910] Timer tick, asking Buildbucket for the build status
[22:38:43.284] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:43.284] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:105:0) after 1m0s
[22:39:43.352] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:105:0)
[22:39:43.352] Timer tick, asking Buildbucket for the build status
[22:39:43.448] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:43.448] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:106:0) after 1m0s
[22:40:43.493] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:106:0)
[22:40:43.493] Timer tick, asking Buildbucket for the build status
[22:40:43.591] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:43.591] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:107:0) after 1m0s
[22:41:43.681] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:107:0)
[22:41:43.681] Timer tick, asking Buildbucket for the build status
[22:41:43.797] Build 8853929178509875440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:43.797] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019359240457351024:108:0) after 1m0s
[22:42:22.279] Received PubSub notification, asking Buildbucket for the build status
[22:42:22.737] Build 8853929178509875440: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[22:42:22.737] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614638540199757",
"created_by": "project:chromium",
"created_ts": "1614632165383261",
"id": "8853929178509875440",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e0fdf1c43eede1d9519303e638c34792a25faf9d\"}}",
"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\": \"e0fdf1c43eede1d9519303e638c34792a25faf9d\", \"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-2-2mmz\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17580, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-3\", \"got_angle_revision\": \"0e11e49d1937d44ffb6345b115d13cf8cee24695\", \"got_dawn_revision\": \"19f111f69c550f5cddec76557a0b7808adae9c66\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"e0fdf1c43eede1d9519303e638c34792a25faf9d\", \"got_revision_cp\": \"refs/heads/master@{#858681}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"106e01583a3ac2fb950777390dde2dcc31a8f161\", \"got_v8_revision\": \"3f362e70f9f173c4a382085977dd64166cc37ef6\", \"got_v8_revision_cp\": \"refs/heads/9.1.5@{#1}\", \"got_webrtc_revision\": \"376cf384ac689eca0bf8d4d1d3a3cf80888d3fbb\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33348}\", \"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\": \"e0fdf1c43eede1d9519303e638c34792a25faf9d\", \"swarm_hashes_refs/heads/master(at){#858681}_without_patch\": {\"absl_hardening_tests\": \"1b5043f3bc6deba4b15408889c387b1a5cd8cba5\", \"accessibility_unittests\": \"0d0d92cfe6bdfcb4af15c3b853757ca94614485f\", \"angle_unittests\": \"f108909a8bbd77df5e1a4fb6c607311da67eee87\", \"app_shell_unittests\": \"f806a9a9fba9dd9b8551a5f277237922ab952ac8\", \"aura_unittests\": \"f3801338aaac1fbd0032bf77c78c3ccad6c53b87\", \"base_unittests\": \"4780e47600b265e30329ca90bae4f8caca4effa5\", \"base_util_unittests\": \"10a9a7e09b4132d0d94ba7a15d539104e9512ab4\", \"blink_common_unittests\": \"618896b693def27831a5b702a652820e5236477c\", \"blink_fuzzer_unittests\": \"08d2b054b998177388e62c14795dc0c150c9d165\", \"blink_heap_unittests\": \"cae0ecc4287bf7d3873480ca6ecd479260237f75\", \"blink_platform_unittests\": \"01b05816e78601d6e73ed78ba3d60afcc8eedd08\", \"blink_unittests\": \"ec7489ab0fc7503af91f1f31e35aac624c3c9455\", \"boringssl_crypto_tests\": \"9275fb00c1be9361aebb5691441e710667f22032\", \"boringssl_ssl_tests\": \"d6da55811d855f1071b3db43a4c4e2d9925f4634\", \"browser_switcher_bho_unittests\": \"aff567fd1c76b94c3f80cd0017980a4d5628e85f\", \"browser_tests\": \"491af5db2969d85c27c519880cd01be68672607a\", \"capture_unittests\": \"18cbebe79db02a300612d4cb64f22a832c3ad68e\", \"cast_unittests\": \"b006b52fcab47c59f9154bfe8d9340f2305a960e\", \"cc_unittests\": \"a4ae37a932e1f2774d3262e7dacbadab4cf4cf1f\", \"chrome_app_unittests\": \"be4e3bcad89f3aae68653d5401916d16a6f9cbae\", \"chrome_cleaner_unittests\": \"9f5bcca5a730e54858a5cd6c004d131b35e387a7\", \"chrome_elf_unittests\": \"7ebaa869f507475756b95aefa47483f2c9cea810\", \"chromedriver_unittests\": \"c5b85b7dc520c2e8f986fd5316a88dc5db7bbf38\", \"color_unittests\": \"e798e5147e2ef3c33c3593a912afc62b2d1abad9\", \"components_browsertests\": \"71a033a0f04c65a5f0a69b613e395dc5231e9c1d\", \"components_unittests\": \"bbc85c9fb5e69147c3b7bbd533dfeaf2630a88c9\", \"compositor_unittests\": \"ce613852ed5c5a3fab2c77c68adf13d0772c1b99\", \"content_browsertests\": \"e3a16ac69763364eb045a8cf899f31453d74af15\", \"content_unittests\": \"75ee753bb0173538d21ec08cbcc88d4534f117d0\", \"courgette_unittests\": \"ae96df604519beea0da21a0f294036a55682f2ea\", \"crashpad_tests\": \"1de692d5204e0bfd8af13a4813974387b8367195\", \"cronet_tests\": \"f89d22b855cfe42fa6b01f5e5e4275ce2cf7f6dc\", \"cronet_unittests\": \"c805835a1e3cf328a2e32f02f3d116ceaad72c15\", \"crypto_unittests\": \"51876f80f37471d764ca0f924225785b98dc98d8\", \"delayloads_unittests\": \"58547c6e1d95adca30a95d60158ce8df3b7de29d\", \"device_unittests\": \"fdbd1a9916120c92656eacbdbdaddb7bb7469734\", \"display_unittests\": \"b8c895f95548aab9b140088a4c847027761115d1\", \"elevation_service_unittests\": \"5f21b73b0185cc5e24bd9cdd31c0db85d83b00cd\", \"events_unittests\": \"9aa859e4b66889441b83e413272ff08604ebe6e0\", \"extensions_browsertests\": \"c4eddd71d86f8319f05967ba0fea8ed5b91866e3\", \"extensions_unittests\": \"c998b80782176b6f16d36e31189303d8370ca118\", \"filesystem_service_unittests\": \"99563910c460d0e3cd49dae6d5d917729d24b248\", \"gcm_unit_tests\": \"32153c4b5523732465f3c136e192042382d9c827\", \"gcp_unittests\": \"ff9031d88d2fb053654d30ba5b4c5e3865c78da4\", \"gfx_unittests\": \"862cd5523dac9d98b64a2510016410f1c62fda8f\", \"gin_unittests\": \"11b7e479f4c63ac0ab5a5439bdb864cdf03cba83\", \"google_apis_unittests\": \"bacb6fb1379ca4f654e3751d5862d7ed427e1a5f\", \"gpu_unittests\": \"314e6bca3ea5a6b7ebc5e87483b503fd3c736214\", \"gwp_asan_unittests\": \"c210a0e35efd9aeaa90f699f6681dba2aeeecd3c\", \"headless_browsertests\": \"ed3b5ab7b3d0e1131ec1aa28c46bd899753cb59f\", \"headless_unittests\": \"e0e1b738fee29e43c5e6a87df702aa6a816ca98a\", \"install_static_unittests\": \"71486bccd080d49c8e4083bcab6e46595e8d380e\", \"installer_util_unittests\": \"9c1092452723147c279377efec19d2126451b6ba\", \"interactive_ui_tests\": \"01e1d88cc1ca169df4e1753930ed8456a142b7ee\", \"ipc_tests\": \"15c5fd9aa1db49d3a2ef85f77668c8f4ec649bcf\", \"jingle_unittests\": \"97438e5514952963538d2cf71d75b3d4c10b5429\", \"latency_unittests\": \"d7ccefcb32ff51344fe38a7a28dc7dad39b5855f\", \"libjingle_xmpp_unittests\": \"2f383e746cd7774265c537058986a06b4a0b120e\", \"liburlpattern_unittests\": \"7227ed4502448a382328a7a74952ae6a78b72562\", \"media_blink_unittests\": \"6f1ff6dca3e3143185e69cd334a0bc919c7a7153\", \"media_unittests\": \"839f00ed170fb89e80755b105796fb515ce2c64a\", \"message_center_unittests\": \"3a14190271716befb5ce9bf24d7471e29e48c712\", \"midi_unittests\": \"1a3e640e03c19ef55a4f9427b5fd6b8622071ba5\", \"mojo_core_unittests\": \"cafa66f39c1471a90c9af62d7155c72d2931d424\", \"mojo_unittests\": \"c6e8810264fe4f0d93cfe3254c3b34621936b087\", \"nacl_loader_unittests\": \"02e29fffbf86d196b076a7116754ed0b2ab29680\", \"native_theme_unittests\": \"35a79c8df3437082f11acc2a6617e910f5758778\", \"net_unittests\": \"a573754258c2854cf61fc087a314e738955a65a0\", \"notification_helper_unittests\": \"f72c4e3d634c5cb9f7fbbae6d829065f14dd8b66\", \"pdf_unittests\": \"cc93de595366e537db7b7a6cb2ed4727581eef29\", \"perfetto_unittests\": \"de0f20ccf1f1186258f6f8ee582eb00390c39b2c\", \"ppapi_unittests\": \"3921202dea0848388fb9a16e5a211da49bc98a67\", \"printing_unittests\": \"97aefa39423b89573328ad502836c86554cfb487\", \"remoting_unittests\": \"84b88c1f63f9de546f93185d29a7791fc9cc1cd3\", \"sbox_integration_tests\": \"6c984b01b5093d690182667c5b31c4570ecb326a\", \"sbox_unittests\": \"edc7a262b61464413f0df03f633648a20edb9c0a\", \"sbox_validation_tests\": \"e1a8d194837dd0ac18aeecb79a4e4ce9f1ae04a8\", \"service_manager_unittests\": \"9a23e634a56941232a3f6cbf5be21da09c88734a\", \"services_unittests\": \"32c82f7ef0874b15abeda96960fee3d0b4fc0b5a\", \"setup_unittests\": \"d3861f24959c892ca926ce51c1eeb76cff887987\", \"shell_dialogs_unittests\": \"9ac8e4f49777f4c4467962e688ee323034bbd1b0\", \"skia_unittests\": \"f155240ab1b0203ae8ad4273c27c43899ad51c2d\", \"snapshot_unittests\": \"56d8b02512ad8462444bf2a9e5a5c259276798bd\", \"sql_unittests\": \"44fbfc3a8fda3465caa8feccc5d9f916bb144fdd\", \"storage_unittests\": \"8382c0be94afd9cc26316b3b82d73044422a09af\", \"sync_integration_tests\": \"0fa2e4052862ba5c2a7da1e179a3a80f85ef435d\", \"traffic_annotation_auditor_unittests\": \"fbc38e7d64570984065a91a3b35a0ec799414c81\", \"ui_base_unittests\": \"ad08bfa4f2d9916f99d78f7232ddb041d0df73ce\", \"ui_touch_selection_unittests\": \"03fa24c4fae1cfe324bfc02b5edf13a097f1e2fb\", \"unit_tests\": \"3661ab12f71de39d78215f17d074aed4f4ddcd9a\", \"updater_tests\": \"6a2b550be6b0d43e8f26a335ff61ec7afd129fb8\", \"url_unittests\": \"31c891c579dcd1361809fd8fd3368e4df13faa8e\", \"views_unittests\": \"65a3f5328d46ec34925b1f2975271cdc5c1e0b0c\", \"viz_unittests\": \"dece49cb4358e9226da389ef0bfa2374e58b079c\", \"vr_common_unittests\": \"1ad5f1fd1ced06762e022323721905913fefe468\", \"vr_pixeltests\": \"f3304b482e685113e7231f6ec6a366595e75e589\", \"weblayer_browsertests\": \"0842d86fd2daa3634fdfae7da48ef0fbee49b43b\", \"weblayer_unittests\": \"e0a8abf52b603f665a6252ec0c304756f7447f58\", \"wm_unittests\": \"6914787516f0339592b8e430bc780dff759f0d92\", \"wtf_unittests\": \"81e0e54046acea376a763ed32c732ec3ada684d6\", \"zlib_unittests\": \"d2c4008d0188c27b8afb7094e8d8c24696c3454b\", \"zucchini_unittests\": \"bb6c3055ab9f220a08d136dc25be97fa531e36da\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_02613bacee8cd1c41158238da4ce7615021ecb28a4b52e6029f89cb601af0790_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-2-2mmz\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"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\": [\"5712-339372b\"], \"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": "1614632405222978",
"status": "COMPLETED",
"status_changed_ts": "1614638540798534",
"tags": [
"build_address:luci.chromium.ci/win-asan/17580",
"builder:win-asan",
"buildset:commit/git/e0fdf1c43eede1d9519303e638c34792a25faf9d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e0fdf1c43eede1d9519303e638c34792a25faf9d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019359240457351024",
"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/8853929178509875440/+/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:5208652b4bf0a210",
"user_agent:luci-scheduler"
],
"updated_ts": "1614638540802842",
"url": "https://ci.chromium.org/b/8853929178509875440",
"utcnow_ts": "1614638542720289"
}
}
[22:42:22.737] Invocation finished in 1h46m18.594934133s with status SUCCEEDED