[19:01:53.306] New invocation is queued and will start shortly
[19:01:54.596] Starting the invocation (attempt 1)
[19:01:54.652] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:01:54.652] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:01:54.653] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022537317923334480",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"0bce46cedfb1c3911706ddb60b22529cc06ff596\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9022537317923334480",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/0bce46cedfb1c3911706ddb60b22529cc06ff596",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0bce46cedfb1c3911706ddb60b22529cc06ff596",
"gitiles_ref:refs/heads/master"
]
}
[19:01:56.111] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611601314827198",
"id": "8857107255662498880",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0bce46cedfb1c3911706ddb60b22529cc06ff596\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611601315952524",
"tags": [
"build_address:luci.chromium.ci/win-asan/17008",
"builder:win-asan",
"buildset:commit/git/0bce46cedfb1c3911706ddb60b22529cc06ff596",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0bce46cedfb1c3911706ddb60b22529cc06ff596",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022537317923334480",
"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/8857107255662498880/+/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": "1611601315952682",
"url": "https://ci.chromium.org/b/8857107255662498880",
"utcnow_ts": "1611601316102524"
}
}
[19:01:56.111] Task URL: https://ci.chromium.org/b/8857107255662498880
[19:01:56.111] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:2:0) after 1m0s
[19:02:56.164] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:2:0)
[19:02:56.164] Timer tick, asking Buildbucket for the build status
[19:02:56.566] Build 8857107255662498880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:02:56.566] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:3:0) after 1m0s
[19:03:56.592] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:3:0)
[19:03:56.592] Timer tick, asking Buildbucket for the build status
[19:03:56.712] Build 8857107255662498880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:03:56.712] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:4:0) after 1m0s
[19:04:56.720] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:4:0)
[19:04:56.720] Timer tick, asking Buildbucket for the build status
[19:04:56.798] Build 8857107255662498880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:04:56.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:5:0) after 1m0s
[19:05:56.854] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:5:0)
[19:05:56.854] Timer tick, asking Buildbucket for the build status
[19:05:57.621] Build 8857107255662498880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:05:57.622] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:6:0) after 1m0s
[19:06:34.372] Received PubSub notification, asking Buildbucket for the build status
[19:06:34.686] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:57.630] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:6:0)
[19:06:57.630] Timer tick, asking Buildbucket for the build status
[19:06:57.737] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:57.737] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:8:0) after 1m0s
[19:07:58.115] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:8:0)
[19:07:58.115] Timer tick, asking Buildbucket for the build status
[19:07:58.239] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:58.239] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:9:0) after 1m0s
[19:08:58.483] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:9:0)
[19:08:58.483] Timer tick, asking Buildbucket for the build status
[19:08:58.632] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:58.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:10:0) after 1m0s
[19:09:58.660] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:10:0)
[19:09:58.660] Timer tick, asking Buildbucket for the build status
[19:09:58.846] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:58.846] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:11:0) after 1m0s
[19:10:59.025] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:11:0)
[19:10:59.025] Timer tick, asking Buildbucket for the build status
[19:10:59.126] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:59.126] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:12:0) after 1m0s
[19:11:59.521] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:12:0)
[19:11:59.521] Timer tick, asking Buildbucket for the build status
[19:11:59.954] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:59.955] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:13:0) after 1m0s
[19:13:00.220] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:13:0)
[19:13:00.220] Timer tick, asking Buildbucket for the build status
[19:13:00.329] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:00.329] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:14:0) after 1m0s
[19:14:00.722] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:14:0)
[19:14:00.722] Timer tick, asking Buildbucket for the build status
[19:14:01.204] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:01.204] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:15:0) after 1m0s
[19:15:01.492] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:15:0)
[19:15:01.492] Timer tick, asking Buildbucket for the build status
[19:15:02.153] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:02.153] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:16:0) after 1m0s
[19:16:02.292] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:16:0)
[19:16:02.292] Timer tick, asking Buildbucket for the build status
[19:16:03.043] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:03.043] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:17:0) after 1m0s
[19:17:03.137] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:17:0)
[19:17:03.137] Timer tick, asking Buildbucket for the build status
[19:17:03.513] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:03.513] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:18:0) after 1m0s
[19:18:03.991] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:18:0)
[19:18:03.991] Timer tick, asking Buildbucket for the build status
[19:18:04.096] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:04.096] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:19:0) after 1m0s
[19:19:04.176] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:19:0)
[19:19:04.176] Timer tick, asking Buildbucket for the build status
[19:19:04.255] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:04.255] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:20:0) after 1m0s
[19:20:04.279] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:20:0)
[19:20:04.279] Timer tick, asking Buildbucket for the build status
[19:20:04.449] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:04.449] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:21:0) after 1m0s
[19:21:04.612] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:21:0)
[19:21:04.612] Timer tick, asking Buildbucket for the build status
[19:21:04.714] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:04.714] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:22:0) after 1m0s
[19:22:04.886] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:22:0)
[19:22:04.886] Timer tick, asking Buildbucket for the build status
[19:22:05.113] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:05.113] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:23:0) after 1m0s
[19:23:05.229] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:23:0)
[19:23:05.229] Timer tick, asking Buildbucket for the build status
[19:23:05.307] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:05.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:24:0) after 1m0s
[19:24:05.374] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:24:0)
[19:24:05.374] Timer tick, asking Buildbucket for the build status
[19:24:05.458] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:05.458] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:25:0) after 1m0s
[19:25:05.488] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:25:0)
[19:25:05.488] Timer tick, asking Buildbucket for the build status
[19:25:05.552] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:05.552] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:26:0) after 1m0s
[19:26:05.626] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:26:0)
[19:26:05.626] Timer tick, asking Buildbucket for the build status
[19:26:05.710] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:05.710] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:27:0) after 1m0s
[19:27:05.749] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:27:0)
[19:27:05.750] Timer tick, asking Buildbucket for the build status
[19:27:05.887] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:05.887] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:28:0) after 1m0s
[19:28:05.953] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:28:0)
[19:28:05.953] Timer tick, asking Buildbucket for the build status
[19:28:06.481] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:06.481] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:29:0) after 1m0s
[19:29:06.550] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:29:0)
[19:29:06.550] Timer tick, asking Buildbucket for the build status
[19:29:06.650] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:06.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:30:0) after 1m0s
[19:30:06.682] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:30:0)
[19:30:06.682] Timer tick, asking Buildbucket for the build status
[19:30:06.764] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:06.764] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:31:0) after 1m0s
[19:31:06.865] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:31:0)
[19:31:06.865] Timer tick, asking Buildbucket for the build status
[19:31:07.002] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:07.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:32:0) after 1m0s
[19:32:07.336] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:32:0)
[19:32:07.336] Timer tick, asking Buildbucket for the build status
[19:32:07.496] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:07.496] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:33:0) after 1m0s
[19:33:07.571] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:33:0)
[19:33:07.571] Timer tick, asking Buildbucket for the build status
[19:33:07.691] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:07.691] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:34:0) after 1m0s
[19:34:07.766] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:34:0)
[19:34:07.766] Timer tick, asking Buildbucket for the build status
[19:34:07.844] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:07.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:35:0) after 1m0s
[19:35:07.873] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:35:0)
[19:35:07.873] Timer tick, asking Buildbucket for the build status
[19:35:07.944] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:07.944] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:36:0) after 1m0s
[19:36:08.104] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:36:0)
[19:36:08.104] Timer tick, asking Buildbucket for the build status
[19:36:08.178] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:08.178] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:37:0) after 1m0s
[19:37:08.715] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:37:0)
[19:37:08.715] Timer tick, asking Buildbucket for the build status
[19:37:08.849] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:08.849] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:38:0) after 1m0s
[19:38:09.025] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:38:0)
[19:38:09.025] Timer tick, asking Buildbucket for the build status
[19:38:09.102] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:09.102] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:39:0) after 1m0s
[19:39:09.291] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:39:0)
[19:39:09.291] Timer tick, asking Buildbucket for the build status
[19:39:09.345] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:09.345] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:40:0) after 1m0s
[19:40:09.481] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:40:0)
[19:40:09.481] Timer tick, asking Buildbucket for the build status
[19:40:09.564] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:09.564] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:41:0) after 1m0s
[19:41:09.803] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:41:0)
[19:41:09.803] Timer tick, asking Buildbucket for the build status
[19:41:09.862] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:09.862] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:42:0) after 1m0s
[19:42:10.039] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:42:0)
[19:42:10.039] Timer tick, asking Buildbucket for the build status
[19:42:10.198] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:10.198] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:43:0) after 1m0s
[19:43:11.221] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:43:0)
[19:43:11.221] Timer tick, asking Buildbucket for the build status
[19:43:11.350] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:11.350] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:44:0) after 1m0s
[19:44:12.402] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:44:0)
[19:44:12.402] Timer tick, asking Buildbucket for the build status
[19:44:12.875] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:12.875] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:45:0) after 1m0s
[19:45:13.065] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:45:0)
[19:45:13.065] Timer tick, asking Buildbucket for the build status
[19:45:13.364] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:13.364] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:46:0) after 1m0s
[19:46:13.719] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:46:0)
[19:46:13.719] Timer tick, asking Buildbucket for the build status
[19:46:13.802] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:13.803] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:47:0) after 1m0s
[19:47:13.974] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:47:0)
[19:47:13.974] Timer tick, asking Buildbucket for the build status
[19:47:14.131] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:14.131] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:48:0) after 1m0s
[19:48:14.791] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:48:0)
[19:48:14.791] Timer tick, asking Buildbucket for the build status
[19:48:14.882] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:14.882] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:49:0) after 1m0s
[19:49:15.003] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:49:0)
[19:49:15.003] Timer tick, asking Buildbucket for the build status
[19:49:15.088] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:15.088] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:50:0) after 1m0s
[19:50:15.233] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:50:0)
[19:50:15.233] Timer tick, asking Buildbucket for the build status
[19:50:15.920] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:15.920] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:51:0) after 1m0s
[19:51:16.078] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:51:0)
[19:51:16.078] Timer tick, asking Buildbucket for the build status
[19:51:16.179] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:16.179] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:52:0) after 1m0s
[19:52:16.401] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:52:0)
[19:52:16.401] Timer tick, asking Buildbucket for the build status
[19:52:17.422] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:17.422] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:53:0) after 1m0s
[19:53:17.723] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:53:0)
[19:53:17.723] Timer tick, asking Buildbucket for the build status
[19:53:18.003] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:18.003] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:54:0) after 1m0s
[19:54:18.128] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:54:0)
[19:54:18.128] Timer tick, asking Buildbucket for the build status
[19:54:18.198] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:18.198] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:55:0) after 1m0s
[19:55:18.268] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:55:0)
[19:55:18.268] Timer tick, asking Buildbucket for the build status
[19:55:18.349] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:18.349] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:56:0) after 1m0s
[19:56:18.502] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:56:0)
[19:56:18.502] Timer tick, asking Buildbucket for the build status
[19:56:18.663] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:18.663] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:57:0) after 1m0s
[19:57:18.700] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:57:0)
[19:57:18.700] Timer tick, asking Buildbucket for the build status
[19:57:18.920] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:18.920] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:58:0) after 1m0s
[19:58:18.929] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:58:0)
[19:58:18.929] Timer tick, asking Buildbucket for the build status
[19:58:19.045] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:19.045] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:59:0) after 1m0s
[19:59:19.227] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:59:0)
[19:59:19.227] Timer tick, asking Buildbucket for the build status
[19:59:19.315] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:19.315] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:60:0) after 1m0s
[20:00:19.449] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:60:0)
[20:00:19.449] Timer tick, asking Buildbucket for the build status
[20:00:19.606] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:19.606] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:61:0) after 1m0s
[20:01:19.693] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:61:0)
[20:01:19.693] Timer tick, asking Buildbucket for the build status
[20:01:19.840] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:19.840] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:62:0) after 1m0s
[20:02:19.882] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:62:0)
[20:02:19.882] Timer tick, asking Buildbucket for the build status
[20:02:19.971] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:19.971] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:63:0) after 1m0s
[20:03:20.106] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:63:0)
[20:03:20.106] Timer tick, asking Buildbucket for the build status
[20:03:20.266] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:20.266] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:64:0) after 1m0s
[20:04:20.340] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:64:0)
[20:04:20.340] Timer tick, asking Buildbucket for the build status
[20:04:20.497] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:20.497] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:65:0) after 1m0s
[20:05:20.572] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:65:0)
[20:05:20.572] Timer tick, asking Buildbucket for the build status
[20:05:20.643] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:20.643] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:66:0) after 1m0s
[20:06:20.712] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:66:0)
[20:06:20.712] Timer tick, asking Buildbucket for the build status
[20:06:20.825] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:20.825] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:67:0) after 1m0s
[20:07:20.923] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:67:0)
[20:07:20.923] Timer tick, asking Buildbucket for the build status
[20:07:21.083] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:21.083] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:68:0) after 1m0s
[20:08:21.130] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:68:0)
[20:08:21.130] Timer tick, asking Buildbucket for the build status
[20:08:21.209] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:21.209] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:69:0) after 1m0s
[20:09:21.354] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:69:0)
[20:09:21.354] Timer tick, asking Buildbucket for the build status
[20:09:21.427] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:21.427] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:70:0) after 1m0s
[20:10:21.631] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:70:0)
[20:10:21.631] Timer tick, asking Buildbucket for the build status
[20:10:21.747] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:21.747] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:71:0) after 1m0s
[20:11:22.109] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:71:0)
[20:11:22.109] Timer tick, asking Buildbucket for the build status
[20:11:22.464] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:22.464] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:72:0) after 1m0s
[20:12:22.539] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:72:0)
[20:12:22.539] Timer tick, asking Buildbucket for the build status
[20:12:22.683] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:22.683] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:73:0) after 1m0s
[20:13:22.824] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:73:0)
[20:13:22.824] Timer tick, asking Buildbucket for the build status
[20:13:23.041] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:23.041] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:74:0) after 1m0s
[20:14:23.084] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:74:0)
[20:14:23.084] Timer tick, asking Buildbucket for the build status
[20:14:23.243] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:23.243] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:75:0) after 1m0s
[20:15:23.283] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:75:0)
[20:15:23.283] Timer tick, asking Buildbucket for the build status
[20:15:23.395] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:23.396] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:76:0) after 1m0s
[20:16:23.400] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:76:0)
[20:16:23.400] Timer tick, asking Buildbucket for the build status
[20:16:23.481] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:23.481] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:77:0) after 1m0s
[20:17:23.535] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:77:0)
[20:17:23.536] Timer tick, asking Buildbucket for the build status
[20:17:23.681] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:23.681] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:78:0) after 1m0s
[20:18:23.719] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:78:0)
[20:18:23.719] Timer tick, asking Buildbucket for the build status
[20:18:23.829] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:23.829] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:79:0) after 1m0s
[20:19:24.343] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:79:0)
[20:19:24.343] Timer tick, asking Buildbucket for the build status
[20:19:24.472] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:24.472] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:80:0) after 1m0s
[20:20:24.506] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:80:0)
[20:20:24.506] Timer tick, asking Buildbucket for the build status
[20:20:24.638] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:24.638] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:81:0) after 1m0s
[20:21:24.727] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:81:0)
[20:21:24.727] Timer tick, asking Buildbucket for the build status
[20:21:24.844] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:24.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:82:0) after 1m0s
[20:22:24.937] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:82:0)
[20:22:24.937] Timer tick, asking Buildbucket for the build status
[20:22:25.064] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:25.064] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:83:0) after 1m0s
[20:23:25.342] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:83:0)
[20:23:25.342] Timer tick, asking Buildbucket for the build status
[20:23:25.576] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:25.576] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:84:0) after 1m0s
[20:24:25.643] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:84:0)
[20:24:25.643] Timer tick, asking Buildbucket for the build status
[20:24:25.791] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:25.791] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:85:0) after 1m0s
[20:25:25.821] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:85:0)
[20:25:25.821] Timer tick, asking Buildbucket for the build status
[20:25:25.935] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:25.935] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:86:0) after 1m0s
[20:26:26.109] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:86:0)
[20:26:26.109] Timer tick, asking Buildbucket for the build status
[20:26:27.401] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:27.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:87:0) after 1m0s
[20:27:27.414] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:87:0)
[20:27:27.414] Timer tick, asking Buildbucket for the build status
[20:27:27.756] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:27.756] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:88:0) after 1m0s
[20:28:27.763] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:88:0)
[20:28:27.763] Timer tick, asking Buildbucket for the build status
[20:28:28.001] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:28.001] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:89:0) after 1m0s
[20:29:28.135] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:89:0)
[20:29:28.135] Timer tick, asking Buildbucket for the build status
[20:29:28.562] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:28.563] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:90:0) after 1m0s
[20:30:28.565] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:90:0)
[20:30:28.565] Timer tick, asking Buildbucket for the build status
[20:30:29.664] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:29.664] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:91:0) after 1m0s
[20:31:29.943] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:91:0)
[20:31:29.943] Timer tick, asking Buildbucket for the build status
[20:31:30.034] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:30.034] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:92:0) after 1m0s
[20:32:30.151] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:92:0)
[20:32:30.151] Timer tick, asking Buildbucket for the build status
[20:32:30.901] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:30.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:93:0) after 1m0s
[20:33:30.947] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:93:0)
[20:33:30.947] Timer tick, asking Buildbucket for the build status
[20:33:31.042] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:31.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:94:0) after 1m0s
[20:34:31.243] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:94:0)
[20:34:31.243] Timer tick, asking Buildbucket for the build status
[20:34:31.354] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:31.354] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:95:0) after 1m0s
[20:35:31.572] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:95:0)
[20:35:31.572] Timer tick, asking Buildbucket for the build status
[20:35:31.803] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:31.803] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:96:0) after 1m0s
[20:36:31.884] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:96:0)
[20:36:31.884] Timer tick, asking Buildbucket for the build status
[20:36:32.223] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:32.223] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:97:0) after 1m0s
[20:37:32.401] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:97:0)
[20:37:32.401] Timer tick, asking Buildbucket for the build status
[20:37:32.534] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:32.534] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:98:0) after 1m0s
[20:38:32.608] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:98:0)
[20:38:32.608] Timer tick, asking Buildbucket for the build status
[20:38:32.690] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:32.690] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:99:0) after 1m0s
[20:39:32.744] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:99:0)
[20:39:32.744] Timer tick, asking Buildbucket for the build status
[20:39:32.853] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:32.853] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:100:0) after 1m0s
[20:40:32.894] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:100:0)
[20:40:32.894] Timer tick, asking Buildbucket for the build status
[20:40:33.458] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:33.458] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:101:0) after 1m0s
[20:41:33.504] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:101:0)
[20:41:33.504] Timer tick, asking Buildbucket for the build status
[20:41:33.765] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:33.765] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:102:0) after 1m0s
[20:42:33.944] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:102:0)
[20:42:33.944] Timer tick, asking Buildbucket for the build status
[20:42:34.259] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:34.259] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:103:0) after 1m0s
[20:43:34.404] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:103:0)
[20:43:34.404] Timer tick, asking Buildbucket for the build status
[20:43:34.477] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:34.477] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:104:0) after 1m0s
[20:44:34.538] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:104:0)
[20:44:34.538] Timer tick, asking Buildbucket for the build status
[20:44:35.163] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:35.163] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:105:0) after 1m0s
[20:45:35.302] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:105:0)
[20:45:35.302] Timer tick, asking Buildbucket for the build status
[20:45:35.368] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:35.368] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:106:0) after 1m0s
[20:46:35.508] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:106:0)
[20:46:35.508] Timer tick, asking Buildbucket for the build status
[20:46:35.629] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:35.629] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:107:0) after 1m0s
[20:47:35.797] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:107:0)
[20:47:35.797] Timer tick, asking Buildbucket for the build status
[20:47:35.961] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:35.961] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:108:0) after 1m0s
[20:48:36.043] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:108:0)
[20:48:36.043] Timer tick, asking Buildbucket for the build status
[20:48:36.354] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:36.354] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:109:0) after 1m0s
[20:49:36.363] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:109:0)
[20:49:36.363] Timer tick, asking Buildbucket for the build status
[20:49:36.510] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:36.510] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:110:0) after 1m0s
[20:50:36.579] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:110:0)
[20:50:36.579] Timer tick, asking Buildbucket for the build status
[20:50:36.653] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:36.654] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:111:0) after 1m0s
[20:51:36.718] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:111:0)
[20:51:36.718] Timer tick, asking Buildbucket for the build status
[20:51:36.863] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:36.863] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:112:0) after 1m0s
[20:52:36.901] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:112:0)
[20:52:36.901] Timer tick, asking Buildbucket for the build status
[20:52:37.097] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:37.097] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:113:0) after 1m0s
[20:53:37.144] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:113:0)
[20:53:37.144] Timer tick, asking Buildbucket for the build status
[20:53:37.220] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:37.220] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:114:0) after 1m0s
[20:54:37.310] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:114:0)
[20:54:37.310] Timer tick, asking Buildbucket for the build status
[20:54:37.398] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:37.398] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:115:0) after 1m0s
[20:55:37.549] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:115:0)
[20:55:37.549] Timer tick, asking Buildbucket for the build status
[20:55:38.095] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:38.095] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:116:0) after 1m0s
[20:56:38.188] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:116:0)
[20:56:38.188] Timer tick, asking Buildbucket for the build status
[20:56:38.792] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:38.792] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:117:0) after 1m0s
[20:57:38.873] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:117:0)
[20:57:38.873] Timer tick, asking Buildbucket for the build status
[20:57:39.073] Build 8857107255662498880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:39.073] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022537317923334480:118:0) after 1m0s
[20:57:48.751] Received PubSub notification, asking Buildbucket for the build status
[20:57:48.860] Build 8857107255662498880: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[20:57:48.860] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611608265946327",
"created_by": "project:chromium",
"created_ts": "1611601314827198",
"id": "8857107255662498880",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0bce46cedfb1c3911706ddb60b22529cc06ff596\"}}",
"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\": \"0bce46cedfb1c3911706ddb60b22529cc06ff596\", \"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-9-snau\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17008, \"clang_revision\": \"llvmorg-12-init-17251-g6de48655-1\", \"got_angle_revision\": \"cd0b933d387a48849f056c7a9ab9e12f328c739f\", \"got_dawn_revision\": \"c00276b2029b85b0cca43ced05173993beaf43e7\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"0bce46cedfb1c3911706ddb60b22529cc06ff596\", \"got_revision_cp\": \"refs/heads/master@{#846805}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"413f7cb17c77c95e4cc3ba141f3c0f049ac2ecb2\", \"got_v8_revision\": \"07aeb34c380e82877710ad10e53596c41c49abd3\", \"got_v8_revision_cp\": \"refs/heads/9.0.61@{#1}\", \"got_webrtc_revision\": \"ded6636cf43904448ee926d1f2b4352c8a957ca6\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33068}\", \"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\": \"0bce46cedfb1c3911706ddb60b22529cc06ff596\", \"swarm_hashes_refs/heads/master(at){#846805}_without_patch\": {\"absl_hardening_tests\": \"5971b5d749e1262b681bb91868d7582f591df947\", \"accessibility_unittests\": \"f761aed04b4c5d1c6286a339b372c396d5a8f497\", \"angle_unittests\": \"6261e51f7f6cacdd05ebbcaf6dcade747a1209d7\", \"app_shell_unittests\": \"7747f4a90e7ff4eb7e7eb81b28539d20c45efaec\", \"aura_unittests\": \"338f7a79f0fec6f51421275f0bc478dd8b14e0f3\", \"base_unittests\": \"83b20ec1eea1674fcc14873cf46f12fcde895ce3\", \"base_util_unittests\": \"f973852b4c2d2a7a4bb3f42f16e0cd683ce643ad\", \"blink_common_unittests\": \"a64ad626b912c75a0d22250889d44ee14df65c5d\", \"blink_fuzzer_unittests\": \"e331ae89448bdb8921b668527df0ed0c966262fa\", \"blink_heap_unittests\": \"0bb37fcd522459c12e705180e49451c7996bc96a\", \"blink_platform_unittests\": \"b0faaab263a01ae9a055a847e73fd7ef04350445\", \"blink_unittests\": \"37f0538715808fe7fb82e27904d78cdfac689afb\", \"boringssl_crypto_tests\": \"60e354047595aca16b6232b146a1faf4f7e51b59\", \"boringssl_ssl_tests\": \"2dce04e5396da34f1d760e68bb01fc633d0bf995\", \"browser_switcher_bho_unittests\": \"f150e8b960e9fca902a80ad5c1cce8a8e7e2220b\", \"browser_tests\": \"f4a5fd60102fbf2c8817a843e3b73291114e21a4\", \"capture_unittests\": \"4609643c253ff3dd0578cd03551c5ecb6fd25cb2\", \"cast_unittests\": \"362fc8473eb150243533aa9c96d5195f5b50dab0\", \"cc_unittests\": \"7be0138e89c95c28f87984c9b952e854397bc5b8\", \"chrome_app_unittests\": \"c0bb945a8830d0883c054a40f6ea9ce807e991ab\", \"chrome_cleaner_unittests\": \"0962ea445bd1fb3c0e5ef1c19a78cd1260b62684\", \"chrome_elf_unittests\": \"310c6eb9c8303f39ef5775997ecd5486bc77e87b\", \"chromedriver_unittests\": \"366006ea0397b36273f9dce967a711d66864392f\", \"components_browsertests\": \"d06ecb215814034b450dbea3eb019606b5411b5f\", \"components_unittests\": \"71287cafc8f6e5dbf891d9743ed180a164f65166\", \"compositor_unittests\": \"767984fd50152e5ed3493a01e9e476918525f0f3\", \"content_browsertests\": \"325a1fe946f61bd6ce7424c06f903dd41f3358de\", \"content_unittests\": \"c706a22e767b47d7cab3b17f7971b74f41c860f8\", \"courgette_unittests\": \"83601a3e53ce387f641ad223794fbe9a600a27b6\", \"crashpad_tests\": \"e36ae9b4df7a54f13a820d3904ea12bf49c771be\", \"cronet_tests\": \"c4732752921bb455be70c645f2c953b4b4cd579d\", \"cronet_unittests\": \"79bf08b2aa98e3a0750b3890947ed0bb56e30ab2\", \"crypto_unittests\": \"7a273f4f38f0c517731f16dbf2d1a2bd33f6f4c5\", \"delayloads_unittests\": \"5e8056f387eae20a80e84837fa75c9e28a10fce9\", \"device_unittests\": \"8edc54561604136ccc20ba3c73225ea8627c6410\", \"display_unittests\": \"70c349e586ae7a588f696e8f0f9cfa74116e3255\", \"elevation_service_unittests\": \"703c16c2cb92a8cc9dde590126059832c023e096\", \"events_unittests\": \"daeb5ac4c27dcd67a78bc92c428158583463aff6\", \"extensions_browsertests\": \"0c7dce64e05a3fe0a21c266e79d1efefae424936\", \"extensions_unittests\": \"d0a1bddddb33f8231d68e46a6e6e85c3365203f1\", \"filesystem_service_unittests\": \"e3a5d0c4384edee16b34bcea17e2d812029422c4\", \"gcm_unit_tests\": \"227eb82c3261e2b5eb5756f0956d55363fa8ba05\", \"gcp_unittests\": \"1a61484134dec7bb3fa29a4b8cb3b126ef8db172\", \"gfx_unittests\": \"8370d045ff220fba19490175afce3355480339ab\", \"gin_unittests\": \"adf84a37895983173f4cbffc9e9776bbea6d91f3\", \"google_apis_unittests\": \"3ed5190e17646ebc5487962ebcd31f69164e2e4e\", \"gpu_unittests\": \"c74e081a0828be96c5057a8d371fc64a45c060ed\", \"gwp_asan_unittests\": \"cff9a09cc539da44c175bd1d12c237a166b7e24a\", \"headless_browsertests\": \"f84fe1f2440680a043cbdcb1d8fd7d89744220ca\", \"headless_unittests\": \"e5742fa73469a524a8e2cdf2c57964fddadf6e00\", \"install_static_unittests\": \"93e517a022eae0f5bc520fc600781b4ac498b360\", \"installer_util_unittests\": \"90263c572675f75b7f48748eb3b81b68ede49fc6\", \"interactive_ui_tests\": \"1b44c358ffd78ba0012762eecd0922904c58d359\", \"ipc_tests\": \"efaccd299db3b0d6e22e5a20592124adb5a62152\", \"jingle_unittests\": \"2935aeb94c2666555c36f36baf97b068ca4b0749\", \"latency_unittests\": \"14e88b9b07262e1472adf73ff9f3602033e8323c\", \"libjingle_xmpp_unittests\": \"08aa55f1c51dfb5a44f0f2e8cc8c7412da333d8e\", \"liburlpattern_unittests\": \"6ea6f655b848fc88a1cd645f251a0176c1be98cf\", \"media_blink_unittests\": \"92c06281092455449a01ad5350a4e6a5e5d59d5f\", \"media_unittests\": \"d2e2ae500136048ee714972a75619e96118d4336\", \"message_center_unittests\": \"5aa8009a2f966fcb9c57fa739a22abd69c7dd250\", \"midi_unittests\": \"69be07b2070e8d2078e015705c4e1b1056a43e22\", \"mojo_core_unittests\": \"d2dc47b31637ae70ba2a5aa38377f08a44b8b1ae\", \"mojo_unittests\": \"fc139e1035f5f90335081d47ebbbfa9a254a3edb\", \"nacl_loader_unittests\": \"520db369a5d8723966522740b9bb2d916669ce9f\", \"native_theme_unittests\": \"f379d95b067f93b886cefeab20a0e5031fcb8648\", \"net_unittests\": \"a7e622d7003ea1145a6bca89c871d9c7c65b2a15\", \"notification_helper_unittests\": \"575863988b20b3d1f3f15fa841106a3fe4563747\", \"pdf_unittests\": \"d85d68c9d362ceb81216709404a3c1395d80b7c0\", \"perfetto_unittests\": \"cd6d215b46ede6f55bf65f1b99d4ce21433cb738\", \"ppapi_unittests\": \"f23af85b32debb3f2d8141633756711e21950110\", \"printing_unittests\": \"159f8f7cf78091247b021ca4535316332518a803\", \"remoting_unittests\": \"2f32b6cf14dd2ed4254269cd09004d0180caff61\", \"sbox_integration_tests\": \"306ddbdb34e8cf8ffb2687fc9da95e0a6d5b19f9\", \"sbox_unittests\": \"c0d6a53fef5318ac5ea75e85618a138df6b3d25f\", \"sbox_validation_tests\": \"620c37c464fe2bb05b1fa86cc7db1d3c7f43375d\", \"service_manager_unittests\": \"0882784bc729c79910af90c5a376290173e92fe1\", \"services_unittests\": \"7bcc3268df10192ad4d372f901851e90e417bf5a\", \"setup_unittests\": \"57b7d91ba4d5f3192b8f8137ac49f8f9219333cf\", \"shell_dialogs_unittests\": \"e29426a8b84d89ba83f6b36fafe9cb98e087a450\", \"skia_unittests\": \"5c2bd4e28685234c0cc7aaad8539988e70aa7adf\", \"snapshot_unittests\": \"7aecfa9c993e19ed6ce6318227915d77d54981f9\", \"sql_unittests\": \"82425230c53570e5d8effe8dab6ae37990b4e45e\", \"storage_unittests\": \"ae5056d71123b8bac876789dcffccd0704a4c145\", \"sync_integration_tests\": \"0dddd35916178670d63ce2a576308a321ee1c4a4\", \"traffic_annotation_auditor_unittests\": \"1e1766398637c995b65237ff932501a96edb5846\", \"ui_base_unittests\": \"45d3bc860806d634bee6b3ed8558604eb60bc511\", \"ui_touch_selection_unittests\": \"13018442257f646e581bcc679af46dd7dc78b22b\", \"unit_tests\": \"18d05565811d5c82df85de1974668c1909452cb2\", \"updater_tests\": \"c430ac5e3d6f276d61099ac824ffdab23dd3b07e\", \"url_unittests\": \"82e4791e794bc79903195138451f7a271f56088e\", \"views_unittests\": \"92d093db37e3da6d1bd922317e927ae01ac79b37\", \"viz_unittests\": \"60728cf91d8e934b9743edcc2599ef4a773e8948\", \"vr_common_unittests\": \"dbf20296d07382350a73d4936706627db7289dd3\", \"vr_pixeltests\": \"6f911a13aab594fb49aefcfe06ff99629d1973b9\", \"weblayer_browsertests\": \"312ddefa5fbe5f0c8fbf93384a82a042028ebcef\", \"weblayer_unittests\": \"eca5ecff207bfc952f15adbb9796580eda6f6017\", \"wm_unittests\": \"8fd081a12174cfe9ab20c6f30f1ff1b29ca23ce2\", \"wtf_unittests\": \"730108cd1bcf0b6978d9de67693262f493a7eff4\", \"zlib_unittests\": \"50652b61942b776ae4a1d996ac9c1a67434ffe7b\", \"zucchini_unittests\": \"5e3dd76a692042cd1f801df272dc461f79d74636\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_27a86a15ec8feb386a5b02e9d1f544a49194bc134f7348519b0bd6cd38b20371_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-9-snau\"], \"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\": [\"5672-4713de1\"], \"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": "1611601593543469",
"status": "COMPLETED",
"status_changed_ts": "1611608266826311",
"tags": [
"build_address:luci.chromium.ci/win-asan/17008",
"builder:win-asan",
"buildset:commit/git/0bce46cedfb1c3911706ddb60b22529cc06ff596",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0bce46cedfb1c3911706ddb60b22529cc06ff596",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022537317923334480",
"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/8857107255662498880/+/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:5153be12ef464810",
"user_agent:luci-scheduler"
],
"updated_ts": "1611608266909304",
"url": "https://ci.chromium.org/b/8857107255662498880",
"utcnow_ts": "1611608268851973"
}
}
[20:57:48.860] Invocation finished in 1h55m55.572377853s with status SUCCEEDED