[23:28:44.254] New invocation is queued and will start shortly
[23:28:45.511] Starting the invocation (attempt 1)
[23:28:45.569] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:28:45.569] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:28:45.570] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9018443665734942848",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"a08c07371b113cd437ac6e4721001f3de67ec8c6\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9018443665734942848",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/a08c07371b113cd437ac6e4721001f3de67ec8c6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a08c07371b113cd437ac6e4721001f3de67ec8c6",
"gitiles_ref:refs/heads/master"
]
}
[23:28:46.265] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1615505325657591",
"id": "8853013603601680832",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a08c07371b113cd437ac6e4721001f3de67ec8c6\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615505326104663",
"tags": [
"build_address:luci.chromium.ci/win-asan/17762",
"builder:win-asan",
"buildset:commit/git/a08c07371b113cd437ac6e4721001f3de67ec8c6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a08c07371b113cd437ac6e4721001f3de67ec8c6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018443665734942848",
"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/8853013603601680832/+/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": "1615505326104769",
"url": "https://ci.chromium.org/b/8853013603601680832",
"utcnow_ts": "1615505326259245"
}
}
[23:28:46.265] Task URL: https://ci.chromium.org/b/8853013603601680832
[23:28:46.265] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:2:0) after 1m0s
[23:29:46.312] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:2:0)
[23:29:46.312] Timer tick, asking Buildbucket for the build status
[23:29:46.496] Build 8853013603601680832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:29:46.496] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:3:0) after 1m0s
[23:30:37.063] Received PubSub notification, asking Buildbucket for the build status
[23:30:37.169] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:46.520] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:3:0)
[23:30:46.520] Timer tick, asking Buildbucket for the build status
[23:30:46.580] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:46.580] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:5:0) after 1m0s
[23:31:46.682] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:5:0)
[23:31:46.682] Timer tick, asking Buildbucket for the build status
[23:31:47.813] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:47.813] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:6:0) after 1m0s
[23:32:47.914] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:6:0)
[23:32:47.914] Timer tick, asking Buildbucket for the build status
[23:32:48.143] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:48.143] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:7:0) after 1m0s
[23:33:48.239] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:7:0)
[23:33:48.239] Timer tick, asking Buildbucket for the build status
[23:33:48.342] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:48.342] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:8:0) after 1m0s
[23:34:48.439] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:8:0)
[23:34:48.439] Timer tick, asking Buildbucket for the build status
[23:34:48.567] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:48.567] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:9:0) after 1m0s
[23:35:48.634] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:9:0)
[23:35:48.634] Timer tick, asking Buildbucket for the build status
[23:35:48.802] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:48.802] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:10:0) after 1m0s
[23:36:48.844] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:10:0)
[23:36:48.844] Timer tick, asking Buildbucket for the build status
[23:36:48.939] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:48.939] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:11:0) after 1m0s
[23:37:49.032] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:11:0)
[23:37:49.032] Timer tick, asking Buildbucket for the build status
[23:37:49.094] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:49.094] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:12:0) after 1m0s
[23:38:49.242] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:12:0)
[23:38:49.242] Timer tick, asking Buildbucket for the build status
[23:38:49.400] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:49.400] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:13:0) after 1m0s
[23:39:49.372] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:13:0)
[23:39:49.372] Timer tick, asking Buildbucket for the build status
[23:39:49.432] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:49.432] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:14:0) after 1m0s
[23:40:49.440] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:14:0)
[23:40:49.440] Timer tick, asking Buildbucket for the build status
[23:40:49.596] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:49.596] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:15:0) after 1m0s
[23:41:49.617] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:15:0)
[23:41:49.617] Timer tick, asking Buildbucket for the build status
[23:41:49.681] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:49.682] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:16:0) after 1m0s
[23:42:49.709] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:16:0)
[23:42:49.709] Timer tick, asking Buildbucket for the build status
[23:42:49.791] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:49.791] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:17:0) after 1m0s
[23:43:49.845] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:17:0)
[23:43:49.845] Timer tick, asking Buildbucket for the build status
[23:43:49.961] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:49.961] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:18:0) after 1m0s
[23:44:49.980] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:18:0)
[23:44:49.980] Timer tick, asking Buildbucket for the build status
[23:44:50.046] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:50.046] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:19:0) after 1m0s
[23:45:50.055] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:19:0)
[23:45:50.055] Timer tick, asking Buildbucket for the build status
[23:45:50.195] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:50.195] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:20:0) after 1m0s
[23:46:50.197] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:20:0)
[23:46:50.197] Timer tick, asking Buildbucket for the build status
[23:46:50.267] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:50.267] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:21:0) after 1m0s
[23:47:50.295] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:21:0)
[23:47:50.295] Timer tick, asking Buildbucket for the build status
[23:47:50.785] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:50.785] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:22:0) after 1m0s
[23:48:51.095] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:22:0)
[23:48:51.095] Timer tick, asking Buildbucket for the build status
[23:48:51.182] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:51.182] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:23:0) after 1m0s
[23:49:51.216] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:23:0)
[23:49:51.216] Timer tick, asking Buildbucket for the build status
[23:49:51.283] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:51.283] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:24:0) after 1m0s
[23:50:51.309] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:24:0)
[23:50:51.309] Timer tick, asking Buildbucket for the build status
[23:50:51.506] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:51.506] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:25:0) after 1m0s
[23:51:51.541] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:25:0)
[23:51:51.541] Timer tick, asking Buildbucket for the build status
[23:51:51.803] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:51.803] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:26:0) after 1m0s
[23:52:51.808] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:26:0)
[23:52:51.808] Timer tick, asking Buildbucket for the build status
[23:52:52.017] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:52.017] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:27:0) after 1m0s
[23:53:52.031] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:27:0)
[23:53:52.031] Timer tick, asking Buildbucket for the build status
[23:53:52.113] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:52.113] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:28:0) after 1m0s
[23:54:52.179] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:28:0)
[23:54:52.179] Timer tick, asking Buildbucket for the build status
[23:54:52.247] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:52.247] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:29:0) after 1m0s
[23:55:52.289] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:29:0)
[23:55:52.289] Timer tick, asking Buildbucket for the build status
[23:55:52.473] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:52.473] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:30:0) after 1m0s
[23:56:52.491] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:30:0)
[23:56:52.491] Timer tick, asking Buildbucket for the build status
[23:56:52.576] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:52.576] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:31:0) after 1m0s
[23:57:52.644] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:31:0)
[23:57:52.644] Timer tick, asking Buildbucket for the build status
[23:57:52.702] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:52.702] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:32:0) after 1m0s
[23:58:52.738] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:32:0)
[23:58:52.738] Timer tick, asking Buildbucket for the build status
[23:58:52.843] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:52.843] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:33:0) after 1m0s
[23:59:52.875] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:33:0)
[23:59:52.875] Timer tick, asking Buildbucket for the build status
[23:59:53.579] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:53.579] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:34:0) after 1m0s
[00:00:53.678] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:34:0)
[00:00:53.678] Timer tick, asking Buildbucket for the build status
[00:00:53.863] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:53.863] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:35:0) after 1m0s
[00:01:53.880] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:35:0)
[00:01:53.880] Timer tick, asking Buildbucket for the build status
[00:01:54.005] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:54.005] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:36:0) after 1m0s
[00:02:54.046] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:36:0)
[00:02:54.046] Timer tick, asking Buildbucket for the build status
[00:02:54.121] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:54.121] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:37:0) after 1m0s
[00:03:54.345] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:37:0)
[00:03:54.345] Timer tick, asking Buildbucket for the build status
[00:03:54.439] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:54.439] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:38:0) after 1m0s
[00:04:54.499] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:38:0)
[00:04:54.499] Timer tick, asking Buildbucket for the build status
[00:04:54.646] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:54.646] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:39:0) after 1m0s
[00:05:54.663] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:39:0)
[00:05:54.663] Timer tick, asking Buildbucket for the build status
[00:05:54.740] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:54.740] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:40:0) after 1m0s
[00:06:54.767] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:40:0)
[00:06:54.767] Timer tick, asking Buildbucket for the build status
[00:06:54.833] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:54.833] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:41:0) after 1m0s
[00:07:54.935] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:41:0)
[00:07:54.935] Timer tick, asking Buildbucket for the build status
[00:07:54.999] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:54.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:42:0) after 1m0s
[00:08:55.060] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:42:0)
[00:08:55.060] Timer tick, asking Buildbucket for the build status
[00:08:55.177] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:55.177] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:43:0) after 1m0s
[00:09:55.214] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:43:0)
[00:09:55.214] Timer tick, asking Buildbucket for the build status
[00:09:55.439] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:55.439] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:44:0) after 1m0s
[00:10:55.459] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:44:0)
[00:10:55.459] Timer tick, asking Buildbucket for the build status
[00:10:55.555] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:55.555] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:45:0) after 1m0s
[00:11:56.049] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:45:0)
[00:11:56.049] Timer tick, asking Buildbucket for the build status
[00:11:56.154] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:56.154] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:46:0) after 1m0s
[00:12:56.165] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:46:0)
[00:12:56.165] Timer tick, asking Buildbucket for the build status
[00:12:56.256] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:56.256] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:47:0) after 1m0s
[00:13:56.261] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:47:0)
[00:13:56.261] Timer tick, asking Buildbucket for the build status
[00:13:56.349] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:56.349] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:48:0) after 1m0s
[00:14:56.378] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:48:0)
[00:14:56.382] Timer tick, asking Buildbucket for the build status
[00:14:56.464] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:56.464] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:49:0) after 1m0s
[00:15:56.560] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:49:0)
[00:15:56.560] Timer tick, asking Buildbucket for the build status
[00:15:56.637] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:56.637] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:50:0) after 1m0s
[00:16:56.779] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:50:0)
[00:16:56.779] Timer tick, asking Buildbucket for the build status
[00:16:56.853] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:56.853] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:51:0) after 1m0s
[00:17:56.891] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:51:0)
[00:17:56.891] Timer tick, asking Buildbucket for the build status
[00:17:56.965] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:56.965] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:52:0) after 1m0s
[00:18:57.109] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:52:0)
[00:18:57.109] Timer tick, asking Buildbucket for the build status
[00:18:57.204] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:57.204] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:53:0) after 1m0s
[00:19:57.308] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:53:0)
[00:19:57.308] Timer tick, asking Buildbucket for the build status
[00:19:57.381] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:57.381] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:54:0) after 1m0s
[00:20:57.484] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:54:0)
[00:20:57.484] Timer tick, asking Buildbucket for the build status
[00:20:57.879] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:57.879] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:55:0) after 1m0s
[00:21:58.110] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:55:0)
[00:21:58.110] Timer tick, asking Buildbucket for the build status
[00:21:58.316] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:58.316] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:56:0) after 1m0s
[00:22:58.378] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:56:0)
[00:22:58.378] Timer tick, asking Buildbucket for the build status
[00:22:58.445] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:58.445] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:57:0) after 1m0s
[00:23:58.479] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:57:0)
[00:23:58.479] Timer tick, asking Buildbucket for the build status
[00:23:58.564] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:58.564] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:58:0) after 1m0s
[00:24:58.627] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:58:0)
[00:24:58.627] Timer tick, asking Buildbucket for the build status
[00:24:58.694] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:58.694] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:59:0) after 1m0s
[00:25:58.763] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:59:0)
[00:25:58.763] Timer tick, asking Buildbucket for the build status
[00:25:58.830] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:58.830] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:60:0) after 1m0s
[00:26:58.978] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:60:0)
[00:26:58.978] Timer tick, asking Buildbucket for the build status
[00:26:59.043] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:59.043] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:61:0) after 1m0s
[00:27:59.196] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:61:0)
[00:27:59.197] Timer tick, asking Buildbucket for the build status
[00:27:59.546] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:59.546] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:62:0) after 1m0s
[00:28:59.557] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:62:0)
[00:28:59.557] Timer tick, asking Buildbucket for the build status
[00:28:59.907] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:59.907] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:63:0) after 1m0s
[00:29:59.968] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:63:0)
[00:29:59.968] Timer tick, asking Buildbucket for the build status
[00:30:00.171] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:00.171] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:64:0) after 1m0s
[00:31:00.208] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:64:0)
[00:31:00.208] Timer tick, asking Buildbucket for the build status
[00:31:00.284] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:00.284] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:65:0) after 1m0s
[00:32:00.320] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:65:0)
[00:32:00.320] Timer tick, asking Buildbucket for the build status
[00:32:00.536] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:00.536] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:66:0) after 1m0s
[00:33:00.592] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:66:0)
[00:33:00.592] Timer tick, asking Buildbucket for the build status
[00:33:00.682] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:00.682] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:67:0) after 1m0s
[00:34:01.102] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:67:0)
[00:34:01.102] Timer tick, asking Buildbucket for the build status
[00:34:01.272] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:01.272] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:68:0) after 1m0s
[00:35:01.366] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:68:0)
[00:35:01.366] Timer tick, asking Buildbucket for the build status
[00:35:01.458] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:01.458] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:69:0) after 1m0s
[00:36:01.629] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:69:0)
[00:36:01.629] Timer tick, asking Buildbucket for the build status
[00:36:01.720] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:01.720] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:70:0) after 1m0s
[00:37:01.759] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:70:0)
[00:37:01.759] Timer tick, asking Buildbucket for the build status
[00:37:02.228] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:02.228] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:71:0) after 1m0s
[00:38:02.329] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:71:0)
[00:38:02.329] Timer tick, asking Buildbucket for the build status
[00:38:02.411] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:02.411] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:72:0) after 1m0s
[00:39:02.432] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:72:0)
[00:39:02.432] Timer tick, asking Buildbucket for the build status
[00:39:02.530] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:02.530] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:73:0) after 1m0s
[00:40:02.564] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:73:0)
[00:40:02.564] Timer tick, asking Buildbucket for the build status
[00:40:02.656] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:02.656] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:74:0) after 1m0s
[00:41:02.692] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:74:0)
[00:41:02.692] Timer tick, asking Buildbucket for the build status
[00:41:02.804] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:02.804] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:75:0) after 1m0s
[00:42:02.887] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:75:0)
[00:42:02.887] Timer tick, asking Buildbucket for the build status
[00:42:02.999] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:02.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:76:0) after 1m0s
[00:43:03.079] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:76:0)
[00:43:03.079] Timer tick, asking Buildbucket for the build status
[00:43:03.172] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:03.172] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:77:0) after 1m0s
[00:44:03.212] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:77:0)
[00:44:03.212] Timer tick, asking Buildbucket for the build status
[00:44:03.308] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:03.308] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:78:0) after 1m0s
[00:45:03.352] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:78:0)
[00:45:03.352] Timer tick, asking Buildbucket for the build status
[00:45:03.435] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:03.435] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:79:0) after 1m0s
[00:46:03.462] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:79:0)
[00:46:03.462] Timer tick, asking Buildbucket for the build status
[00:46:03.570] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:03.570] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:80:0) after 1m0s
[00:47:03.613] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:80:0)
[00:47:03.613] Timer tick, asking Buildbucket for the build status
[00:47:03.698] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:03.698] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:81:0) after 1m0s
[00:48:03.726] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:81:0)
[00:48:03.726] Timer tick, asking Buildbucket for the build status
[00:48:03.815] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:03.815] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:82:0) after 1m0s
[00:49:03.929] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:82:0)
[00:49:03.929] Timer tick, asking Buildbucket for the build status
[00:49:04.126] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:04.126] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:83:0) after 1m0s
[00:50:04.172] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:83:0)
[00:50:04.172] Timer tick, asking Buildbucket for the build status
[00:50:04.417] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:04.417] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:84:0) after 1m0s
[00:51:04.482] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:84:0)
[00:51:04.482] Timer tick, asking Buildbucket for the build status
[00:51:04.595] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:04.595] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:85:0) after 1m0s
[00:52:04.634] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:85:0)
[00:52:04.634] Timer tick, asking Buildbucket for the build status
[00:52:04.710] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:04.710] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:86:0) after 1m0s
[00:53:04.742] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:86:0)
[00:53:04.742] Timer tick, asking Buildbucket for the build status
[00:53:04.824] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:04.824] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:87:0) after 1m0s
[00:54:04.883] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:87:0)
[00:54:04.883] Timer tick, asking Buildbucket for the build status
[00:54:04.982] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:04.982] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:88:0) after 1m0s
[00:55:05.100] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:88:0)
[00:55:05.100] Timer tick, asking Buildbucket for the build status
[00:55:05.610] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:05.610] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:89:0) after 1m0s
[00:56:05.749] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:89:0)
[00:56:05.749] Timer tick, asking Buildbucket for the build status
[00:56:05.856] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:05.856] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:90:0) after 1m0s
[00:57:05.923] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:90:0)
[00:57:05.923] Timer tick, asking Buildbucket for the build status
[00:57:06.204] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:06.204] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:91:0) after 1m0s
[00:58:06.203] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:91:0)
[00:58:06.203] Timer tick, asking Buildbucket for the build status
[00:58:06.281] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:06.281] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:92:0) after 1m0s
[00:59:06.393] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:92:0)
[00:59:06.393] Timer tick, asking Buildbucket for the build status
[00:59:06.738] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:06.738] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:93:0) after 1m0s
[01:00:06.760] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:93:0)
[01:00:06.760] Timer tick, asking Buildbucket for the build status
[01:00:06.845] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:06.845] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:94:0) after 1m0s
[01:01:06.905] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:94:0)
[01:01:06.905] Timer tick, asking Buildbucket for the build status
[01:01:06.997] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:06.997] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:95:0) after 1m0s
[01:02:07.150] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:95:0)
[01:02:07.150] Timer tick, asking Buildbucket for the build status
[01:02:07.235] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:07.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:96:0) after 1m0s
[01:03:07.312] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:96:0)
[01:03:07.312] Timer tick, asking Buildbucket for the build status
[01:03:07.567] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:07.567] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:97:0) after 1m0s
[01:04:07.635] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:97:0)
[01:04:07.635] Timer tick, asking Buildbucket for the build status
[01:04:07.722] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:07.722] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:98:0) after 1m0s
[01:05:07.795] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:98:0)
[01:05:07.795] Timer tick, asking Buildbucket for the build status
[01:05:07.916] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:07.916] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:99:0) after 1m0s
[01:06:07.962] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:99:0)
[01:06:07.962] Timer tick, asking Buildbucket for the build status
[01:06:08.040] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:08.040] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:100:0) after 1m0s
[01:07:08.094] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:100:0)
[01:07:08.094] Timer tick, asking Buildbucket for the build status
[01:07:08.553] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:08.553] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:101:0) after 1m0s
[01:08:09.176] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:101:0)
[01:08:09.176] Timer tick, asking Buildbucket for the build status
[01:08:09.461] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:09.461] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:102:0) after 1m0s
[01:09:09.609] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:102:0)
[01:09:09.609] Timer tick, asking Buildbucket for the build status
[01:09:09.712] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:09.712] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:103:0) after 1m0s
[01:10:09.802] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:103:0)
[01:10:09.802] Timer tick, asking Buildbucket for the build status
[01:10:09.952] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:09.952] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:104:0) after 1m0s
[01:11:10.067] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:104:0)
[01:11:10.067] Timer tick, asking Buildbucket for the build status
[01:11:10.172] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:10.172] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:105:0) after 1m0s
[01:12:10.265] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:105:0)
[01:12:10.265] Timer tick, asking Buildbucket for the build status
[01:12:10.720] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:10.720] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:106:0) after 1m0s
[01:13:10.766] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:106:0)
[01:13:10.766] Timer tick, asking Buildbucket for the build status
[01:13:11.143] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:11.143] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:107:0) after 1m0s
[01:14:11.171] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:107:0)
[01:14:11.171] Timer tick, asking Buildbucket for the build status
[01:14:11.278] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:11.278] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:108:0) after 1m0s
[01:15:11.257] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:108:0)
[01:15:11.257] Timer tick, asking Buildbucket for the build status
[01:15:11.531] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:11.532] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:109:0) after 1m0s
[01:16:11.577] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:109:0)
[01:16:11.577] Timer tick, asking Buildbucket for the build status
[01:16:11.658] Build 8853013603601680832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:11.658] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018443665734942848:110:0) after 1m0s
[01:16:29.243] Received PubSub notification, asking Buildbucket for the build status
[01:16:29.378] Build 8853013603601680832: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[01:16:29.378] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1615511786529142",
"created_by": "project:chromium",
"created_ts": "1615505325657591",
"id": "8853013603601680832",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a08c07371b113cd437ac6e4721001f3de67ec8c6\"}}",
"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\": \"a08c07371b113cd437ac6e4721001f3de67ec8c6\", \"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-10-bsc3\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17762, \"clang_revision\": \"llvmorg-13-init-3462-gfe5c2c3c-2\", \"got_angle_revision\": \"fcd7affb6bfd9e17d6c12e836158f8f0fc634b14\", \"got_dawn_revision\": \"9f6bc4e3a95a5ae4e009d76812f752340d1d6181\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"a08c07371b113cd437ac6e4721001f3de67ec8c6\", \"got_revision_cp\": \"refs/heads/master@{#862188}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"c9b56017cfc3305b810d02aeb9756fbe256cba6f\", \"got_v8_revision\": \"9d12b666c3e113f5cd61e2b2eb384aae242ac840\", \"got_v8_revision_cp\": \"refs/heads/9.1.105@{#1}\", \"got_webrtc_revision\": \"14a626af56675c9d95f9fb207d53366a53a95fbd\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33409}\", \"is_cached\": true, \"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\": \"a08c07371b113cd437ac6e4721001f3de67ec8c6\", \"swarm_hashes_refs/heads/master(at){#862188}_without_patch\": {\"absl_hardening_tests\": \"7b06f263e83440fa6fc436bb05754881ca1ebe90\", \"accessibility_unittests\": \"935b9f02ddbeedc67f08b30a382f0c049789b791\", \"angle_unittests\": \"8bd11113f700e6e5edee999a757044284030cf86\", \"app_shell_unittests\": \"d3c078389229a4f50801ceece673e7d7536a1028\", \"aura_unittests\": \"2bec208fd7a37f33848f369268c6e40eec27d49e\", \"base_unittests\": \"a424ae68a1c992bd6695ff86c74a2609b2e90ca6\", \"base_util_unittests\": \"089b9ceeb092e6ac407b9fe04addc9c10566bb05\", \"blink_common_unittests\": \"942505e8dea9bedd099ad8d6a1f9b8e22d618130\", \"blink_fuzzer_unittests\": \"d5ca20f1b73e65c0d63a98cf34492b37a7783206\", \"blink_heap_unittests\": \"7c8772dc860874a764a94327de8ca6fdecf79983\", \"blink_platform_unittests\": \"4210840a25649824419b7e14c5edd0b3b1a95e91\", \"blink_unittests\": \"41299452faad4b4e5b80daac4511c320d85b64c1\", \"boringssl_crypto_tests\": \"c9c11867d4fee0e9a994b8374f1bbe25404505f4\", \"boringssl_ssl_tests\": \"9c31ae8af4c236efbe42a1e2b4ae7bec9777d8ad\", \"browser_switcher_bho_unittests\": \"1a38b1c7a356977b78401ef6d2026feaa1be9bff\", \"browser_tests\": \"b6d0b5fa0e5e46c5d1bdf70fb00fecb0b5f93df0\", \"capture_unittests\": \"e94066562b2d24cdc5bb334965cdca83581e71a1\", \"cast_unittests\": \"0948d1927e6013befacda2b822fda4138a96ea50\", \"cc_unittests\": \"535bb6ace2b47ca7877cb08be25ca98c3cf14429\", \"chrome_app_unittests\": \"cb26b54d5fefe43134dd6de32fece1ae4b52a566\", \"chrome_cleaner_unittests\": \"0158a7bebb4805a36117fd3d52ff7bb717332051\", \"chrome_elf_unittests\": \"1b2bdc2cb7f7f283caaddb72cead06611c332868\", \"chromedriver_unittests\": \"7542d4b736bd2c5d9ab8dcf91113b8e8a6110a9b\", \"color_unittests\": \"1d34e9f890c28aa2fec1e757004d9eb8d3652742\", \"components_browsertests\": \"df99d2d87cc1ccfe6bec48d54359a507388a3eea\", \"components_unittests\": \"54a05056bf8a3ebe62c35682a079e70977a5a524\", \"compositor_unittests\": \"067e4c13ceaf1186b458a7c74f373be376ae1640\", \"content_browsertests\": \"bf7566b04427c61cedf0c2e94b43fea8b67f92e0\", \"content_unittests\": \"14a376cb8feaafe61e3745d20b985e670f165156\", \"courgette_unittests\": \"b22543e6448420af10aaf1c25958dc2f23c5de38\", \"crashpad_tests\": \"8656ecd1aca8e98272afb163920b52bef8e669fa\", \"cronet_tests\": \"0f640e2277506c36bf7be3f014b183a7bfdcabe8\", \"cronet_unittests\": \"cf85a23226199d86fb728997df58c16dbc084880\", \"crypto_unittests\": \"40afbdb0b771554b287b707cfb1a889b73e9c299\", \"delayloads_unittests\": \"9e96dc2f6c143b7e4726874b37233c3b5c80b1c2\", \"device_unittests\": \"6c740faf47f3a4fd59303982983724bc3e849b3e\", \"display_unittests\": \"0f0ce5b6e2f2d1f9a5e6b8418e7f0e4f6358feeb\", \"elevation_service_unittests\": \"26e97d3a364f8c02f1a9252744223300d523ec30\", \"events_unittests\": \"0b50d67260b97bb8450cf1dc25ce56f84be21eee\", \"extensions_browsertests\": \"cb8f10de9b884b4510dd9834f412541356680281\", \"extensions_unittests\": \"bb375437497cce50364ddde3d3e8dd53998a331b\", \"filesystem_service_unittests\": \"a27832a772c91e1e06a29ac16e7433ff67215a91\", \"gcm_unit_tests\": \"21489b16c43cde70e3c984e3d1c2bf2bff7d00af\", \"gcp_unittests\": \"3657073b50bbdacbed91b58c14c643ff003080c7\", \"gfx_unittests\": \"34a950af6013572883b87e6a94f3f41a47a4923a\", \"gin_unittests\": \"818048345c6124ed8cce315d72948af639e4089f\", \"google_apis_unittests\": \"e7e9f887093c933c2079d058c2ebb13034ea0b46\", \"gpu_unittests\": \"47f6e3fb6248f0ff93c8a84aae931b5a9c101aa0\", \"gwp_asan_unittests\": \"d8eac8017cf682cce6a9f3c7c7bef23568eb1e89\", \"headless_browsertests\": \"35dab6bc3993297e436c856317fa5f2045160d16\", \"headless_unittests\": \"87c7cf97f23bb95f8dedc02c69535fd53eb50b5f\", \"install_static_unittests\": \"76ca073ae4d44fcfef010ffcc5faa5a2a3394d0c\", \"installer_util_unittests\": \"aacb35eb3ceb5f34a35088fa185ef5caf8787d58\", \"interactive_ui_tests\": \"c1a8a2fbb186b2cf1b732d2fd20d3498602b5af7\", \"ipc_tests\": \"234194f70987ee7b8eb8c6b732164fde8e7b584c\", \"jingle_unittests\": \"34b3a026bc468bccf2dcd9cbc432348e47d16d8b\", \"latency_unittests\": \"3408b9a4c640d4d4ae366972e335726b57f191d0\", \"libjingle_xmpp_unittests\": \"1051f820af61ace76aebacaf883b255fc8bfb34f\", \"liburlpattern_unittests\": \"5258cfefadc7a3454916140fac5ad9483b085ced\", \"media_blink_unittests\": \"9e9002b3f9d9760bb09c50cdc7d5b2b99a38e360\", \"media_unittests\": \"521daf8dee39751fdea6cf96683e969502af8a88\", \"message_center_unittests\": \"3938c52cf2a78b96226e248555675c9409452a55\", \"midi_unittests\": \"2464a80e300fccd7fdd1c6273b79d5a1ff74c1e4\", \"mojo_core_unittests\": \"e391b91ecf118e3bf593fd8afa5c97ecbe9c88ff\", \"mojo_unittests\": \"d5fc787ad8ed1e948856af42d07a2dd3096bf361\", \"nacl_loader_unittests\": \"8dcf4c0c61726ac981c13782e56662833f13b814\", \"native_theme_unittests\": \"930b0a5da4cc1d9464dff1324de6c4f897b121e4\", \"net_unittests\": \"4d7248f820cec6082868ad9d65beb9dd3d0dc007\", \"notification_helper_unittests\": \"55a039f294df915859dac370ff8135743979c9f4\", \"pdf_unittests\": \"ab7aefecd44056a1c47f3b8aced4abf2b51ac3b8\", \"perfetto_unittests\": \"d1b485d50470298d0695372b1e8c65e26bc7bfd4\", \"ppapi_unittests\": \"e0fea6e648a390439b1825db8b4c3f488b1e9014\", \"printing_unittests\": \"26fcd360d6a50680cb1695a602f43dd334c6be8b\", \"remoting_unittests\": \"3431f4d228f6e56764120488f9a9e61877c4de46\", \"sbox_integration_tests\": \"4b53a47cd63aa69745759ced329d3aa103bb0a73\", \"sbox_unittests\": \"2954d6ecef52ca8b4eadd62b963cc9ad8961b375\", \"sbox_validation_tests\": \"b41c563ac0aa5fa1177101c6b0b56822776e3597\", \"service_manager_unittests\": \"5c8a5a2cf75a4c90d3ca45df2c23809061324c0e\", \"services_unittests\": \"355c8f6deaffe0c6bd1fa2c622fc52b5135cc7c6\", \"setup_unittests\": \"683c788133e9273a73fee7bc7e7f40fec748c7d1\", \"shell_dialogs_unittests\": \"f5f7fa979fba28cc09564a50d05437da34436489\", \"skia_unittests\": \"8d266377d7c32782bb76242d0156f0a01ca30189\", \"snapshot_unittests\": \"7ce83cebcb80444cc33da46ebe7ead0c1a81a816\", \"sql_unittests\": \"26601aedbfcabc837be868e44ceefbe5a20306ad\", \"storage_unittests\": \"7525f17989e9900da06d70c3057c2e6bf3ea5edd\", \"sync_integration_tests\": \"35a8a5011797960a6d6cf1f2c9e1a63716596d22\", \"traffic_annotation_auditor_unittests\": \"6fc4578766bb8424d8ba4c01c2b2acbf557290a4\", \"ui_base_unittests\": \"9a97148373783c2134c77c4dc5075af1c6e628d2\", \"ui_touch_selection_unittests\": \"e58e000562690c026cd76f6e0c6a72c5003be060\", \"unit_tests\": \"800475716a70aab692687ef365b6f0282a104f2b\", \"updater_tests\": \"b1edc12f7e3fe4409577c42a662510114e1187c7\", \"url_unittests\": \"8a847365a78c3d1469178ab08437930809746d82\", \"views_unittests\": \"21b4cb4e532f529b8f16fa3497e0733c1395df4d\", \"viz_unittests\": \"d58767a916d0a51882f95711b111af5f5717bfbb\", \"vr_common_unittests\": \"c52dc4bd31476548e30bded8211b99246277a6db\", \"vr_pixeltests\": \"2337879734050725b5a2b58def3009bcd2d2fa29\", \"weblayer_browsertests\": \"d380daef0d925a9d7590f379ec83a6e79a5297b8\", \"weblayer_unittests\": \"9bae78c2c7b16d53b343f2f740b7f234f63a9188\", \"wm_unittests\": \"c78e6224d9c129207328a6c04c42d3e601975412\", \"wtf_unittests\": \"c6fd18538ecb91259ea76aed7d0697d49394cc7f\", \"zlib_unittests\": \"ee78c529a5c13dc76191674322a56889d02ee2e4\", \"zucchini_unittests\": \"df13e9ff8d65799c5f431153fe9b6c5c644a25ea\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-10-bsc3\"], \"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\": [\"5754-8c4f760\"], \"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": "1615505436115192",
"status": "COMPLETED",
"status_changed_ts": "1615511786923579",
"tags": [
"build_address:luci.chromium.ci/win-asan/17762",
"builder:win-asan",
"buildset:commit/git/a08c07371b113cd437ac6e4721001f3de67ec8c6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a08c07371b113cd437ac6e4721001f3de67ec8c6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018443665734942848",
"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/8853013603601680832/+/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:523c70897bcdc710",
"user_agent:luci-scheduler"
],
"updated_ts": "1615511786999030",
"url": "https://ci.chromium.org/b/8853013603601680832",
"utcnow_ts": "1615511789370190"
}
}
[01:16:29.378] Invocation finished in 1h47m45.138930651s with status SUCCEEDED