[20:51:00.800] New invocation is queued and will start shortly
[20:51:02.393] Starting the invocation (attempt 1)
[20:51:02.433] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:51:02.433] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:51:02.433] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9018000604053921664",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"366da1a259a761b9062786370022a7c1c459d36c\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9018000604053921664",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/366da1a259a761b9062786370022a7c1c459d36c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/366da1a259a761b9062786370022a7c1c459d36c",
"gitiles_ref:refs/heads/master"
]
}
[20:51:03.091] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1615927862537341",
"id": "8852570541570865280",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"366da1a259a761b9062786370022a7c1c459d36c\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615927862909228",
"tags": [
"build_address:luci.chromium.ci/win-asan/17823",
"builder:win-asan",
"buildset:commit/git/366da1a259a761b9062786370022a7c1c459d36c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/366da1a259a761b9062786370022a7c1c459d36c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018000604053921664",
"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/8852570541570865280/+/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": "1615927862909346",
"url": "https://ci.chromium.org/b/8852570541570865280",
"utcnow_ts": "1615927863008073"
}
}
[20:51:03.091] Task URL: https://ci.chromium.org/b/8852570541570865280
[20:51:03.091] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:2:0) after 1m0s
[20:52:03.240] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:2:0)
[20:52:03.240] Timer tick, asking Buildbucket for the build status
[20:52:03.326] Build 8852570541570865280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:52:03.326] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:3:0) after 1m0s
[20:53:03.368] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:3:0)
[20:53:03.368] Timer tick, asking Buildbucket for the build status
[20:53:03.541] Build 8852570541570865280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:53:03.541] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:4:0) after 1m0s
[20:54:03.599] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:4:0)
[20:54:03.599] Timer tick, asking Buildbucket for the build status
[20:54:03.665] Build 8852570541570865280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:54:03.665] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:5:0) after 1m0s
[20:55:03.727] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:5:0)
[20:55:03.727] Timer tick, asking Buildbucket for the build status
[20:55:03.788] Build 8852570541570865280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:55:03.788] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:6:0) after 1m0s
[20:55:53.857] Received PubSub notification, asking Buildbucket for the build status
[20:55:54.088] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:03.837] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:6:0)
[20:56:03.837] Timer tick, asking Buildbucket for the build status
[20:56:03.941] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:03.941] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:8:0) after 1m0s
[20:57:03.973] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:8:0)
[20:57:03.973] Timer tick, asking Buildbucket for the build status
[20:57:04.062] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:04.062] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:9:0) after 1m0s
[20:58:04.116] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:9:0)
[20:58:04.116] Timer tick, asking Buildbucket for the build status
[20:58:04.332] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:04.332] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:10:0) after 1m0s
[20:59:04.374] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:10:0)
[20:59:04.374] Timer tick, asking Buildbucket for the build status
[20:59:04.443] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:04.443] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:11:0) after 1m0s
[21:00:04.519] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:11:0)
[21:00:04.519] Timer tick, asking Buildbucket for the build status
[21:00:04.596] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:04.596] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:12:0) after 1m0s
[21:01:04.576] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:12:0)
[21:01:04.576] Timer tick, asking Buildbucket for the build status
[21:01:05.762] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:05.762] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:13:0) after 1m0s
[21:02:05.794] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:13:0)
[21:02:05.794] Timer tick, asking Buildbucket for the build status
[21:02:05.945] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:05.945] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:14:0) after 1m0s
[21:03:06.009] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:14:0)
[21:03:06.009] Timer tick, asking Buildbucket for the build status
[21:03:06.072] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:06.072] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:15:0) after 1m0s
[21:04:06.072] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:15:0)
[21:04:06.072] Timer tick, asking Buildbucket for the build status
[21:04:06.140] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:06.140] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:16:0) after 1m0s
[21:05:06.140] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:16:0)
[21:05:06.140] Timer tick, asking Buildbucket for the build status
[21:05:06.243] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:06.243] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:17:0) after 1m0s
[21:06:06.282] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:17:0)
[21:06:06.282] Timer tick, asking Buildbucket for the build status
[21:06:06.397] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:06.398] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:18:0) after 1m0s
[21:07:06.409] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:18:0)
[21:07:06.409] Timer tick, asking Buildbucket for the build status
[21:07:06.519] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:06.519] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:19:0) after 1m0s
[21:08:06.541] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:19:0)
[21:08:06.541] Timer tick, asking Buildbucket for the build status
[21:08:06.608] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:06.608] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:20:0) after 1m0s
[21:09:06.647] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:20:0)
[21:09:06.647] Timer tick, asking Buildbucket for the build status
[21:09:06.721] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:06.721] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:21:0) after 1m0s
[21:10:06.743] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:21:0)
[21:10:06.743] Timer tick, asking Buildbucket for the build status
[21:10:06.882] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:06.882] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:22:0) after 1m0s
[21:11:06.907] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:22:0)
[21:11:06.907] Timer tick, asking Buildbucket for the build status
[21:11:07.395] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:07.395] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:23:0) after 1m0s
[21:12:07.415] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:23:0)
[21:12:07.415] Timer tick, asking Buildbucket for the build status
[21:12:07.494] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:07.494] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:24:0) after 1m0s
[21:13:07.542] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:24:0)
[21:13:07.542] Timer tick, asking Buildbucket for the build status
[21:13:07.616] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:07.616] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:25:0) after 1m0s
[21:14:07.641] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:25:0)
[21:14:07.641] Timer tick, asking Buildbucket for the build status
[21:14:07.714] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:07.714] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:26:0) after 1m0s
[21:15:07.732] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:26:0)
[21:15:07.732] Timer tick, asking Buildbucket for the build status
[21:15:07.831] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:07.831] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:27:0) after 1m0s
[21:16:07.882] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:27:0)
[21:16:07.882] Timer tick, asking Buildbucket for the build status
[21:16:07.964] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:07.964] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:28:0) after 1m0s
[21:17:08.013] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:28:0)
[21:17:08.013] Timer tick, asking Buildbucket for the build status
[21:17:08.245] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:08.245] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:29:0) after 1m0s
[21:18:08.295] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:29:0)
[21:18:08.295] Timer tick, asking Buildbucket for the build status
[21:18:08.375] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:08.375] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:30:0) after 1m0s
[21:19:08.394] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:30:0)
[21:19:08.394] Timer tick, asking Buildbucket for the build status
[21:19:08.478] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:08.478] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:31:0) after 1m0s
[21:20:08.576] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:31:0)
[21:20:08.576] Timer tick, asking Buildbucket for the build status
[21:20:08.637] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:08.637] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:32:0) after 1m0s
[21:21:08.893] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:32:0)
[21:21:08.893] Timer tick, asking Buildbucket for the build status
[21:21:08.989] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:08.989] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:33:0) after 1m0s
[21:22:09.070] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:33:0)
[21:22:09.070] Timer tick, asking Buildbucket for the build status
[21:22:09.133] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:09.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:34:0) after 1m0s
[21:23:09.264] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:34:0)
[21:23:09.264] Timer tick, asking Buildbucket for the build status
[21:23:09.340] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:09.340] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:35:0) after 1m0s
[21:24:09.368] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:35:0)
[21:24:09.368] Timer tick, asking Buildbucket for the build status
[21:24:09.439] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:09.439] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:36:0) after 1m0s
[21:25:09.461] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:36:0)
[21:25:09.461] Timer tick, asking Buildbucket for the build status
[21:25:09.614] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:09.614] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:37:0) after 1m0s
[21:26:09.756] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:37:0)
[21:26:09.756] Timer tick, asking Buildbucket for the build status
[21:26:09.854] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:09.854] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:38:0) after 1m0s
[21:27:09.896] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:38:0)
[21:27:09.896] Timer tick, asking Buildbucket for the build status
[21:27:10.111] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:10.111] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:39:0) after 1m0s
[21:28:10.142] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:39:0)
[21:28:10.142] Timer tick, asking Buildbucket for the build status
[21:28:10.286] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:10.286] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:40:0) after 1m0s
[21:29:10.296] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:40:0)
[21:29:10.296] Timer tick, asking Buildbucket for the build status
[21:29:10.509] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:10.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:41:0) after 1m0s
[21:30:10.549] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:41:0)
[21:30:10.550] Timer tick, asking Buildbucket for the build status
[21:30:10.621] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:10.621] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:42:0) after 1m0s
[21:31:10.643] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:42:0)
[21:31:10.643] Timer tick, asking Buildbucket for the build status
[21:31:10.870] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:10.870] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:43:0) after 1m0s
[21:32:10.943] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:43:0)
[21:32:10.943] Timer tick, asking Buildbucket for the build status
[21:32:11.223] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:11.223] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:44:0) after 1m0s
[21:33:11.282] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:44:0)
[21:33:11.282] Timer tick, asking Buildbucket for the build status
[21:33:11.356] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:11.356] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:45:0) after 1m0s
[21:34:11.496] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:45:0)
[21:34:11.496] Timer tick, asking Buildbucket for the build status
[21:34:11.589] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:11.589] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:46:0) after 1m0s
[21:35:11.750] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:46:0)
[21:35:11.750] Timer tick, asking Buildbucket for the build status
[21:35:11.813] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:11.813] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:47:0) after 1m0s
[21:36:11.838] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:47:0)
[21:36:11.838] Timer tick, asking Buildbucket for the build status
[21:36:11.915] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:11.915] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:48:0) after 1m0s
[21:37:12.263] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:48:0)
[21:37:12.263] Timer tick, asking Buildbucket for the build status
[21:37:12.338] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:12.338] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:49:0) after 1m0s
[21:38:12.561] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:49:0)
[21:38:12.561] Timer tick, asking Buildbucket for the build status
[21:38:12.626] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:12.626] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:50:0) after 1m0s
[21:39:12.783] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:50:0)
[21:39:12.783] Timer tick, asking Buildbucket for the build status
[21:39:12.844] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:12.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:51:0) after 1m0s
[21:40:12.929] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:51:0)
[21:40:12.929] Timer tick, asking Buildbucket for the build status
[21:40:13.001] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:13.001] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:52:0) after 1m0s
[21:41:13.142] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:52:0)
[21:41:13.142] Timer tick, asking Buildbucket for the build status
[21:41:13.213] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:13.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:53:0) after 1m0s
[21:42:13.448] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:53:0)
[21:42:13.448] Timer tick, asking Buildbucket for the build status
[21:42:14.258] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:14.258] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:54:0) after 1m0s
[21:43:14.785] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:54:0)
[21:43:14.785] Timer tick, asking Buildbucket for the build status
[21:43:14.900] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:14.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:55:0) after 1m0s
[21:44:15.075] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:55:0)
[21:44:15.075] Timer tick, asking Buildbucket for the build status
[21:44:15.168] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:15.168] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:56:0) after 1m0s
[21:45:15.410] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:56:0)
[21:45:15.410] Timer tick, asking Buildbucket for the build status
[21:45:15.478] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:15.478] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:57:0) after 1m0s
[21:46:15.564] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:57:0)
[21:46:15.564] Timer tick, asking Buildbucket for the build status
[21:46:15.643] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:15.643] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:58:0) after 1m0s
[21:47:15.796] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:58:0)
[21:47:15.796] Timer tick, asking Buildbucket for the build status
[21:47:15.902] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:15.902] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:59:0) after 1m0s
[21:48:16.052] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:59:0)
[21:48:16.052] Timer tick, asking Buildbucket for the build status
[21:48:16.306] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:16.306] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:60:0) after 1m0s
[21:49:16.484] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:60:0)
[21:49:16.484] Timer tick, asking Buildbucket for the build status
[21:49:16.557] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:16.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:61:0) after 1m0s
[21:50:16.551] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:61:0)
[21:50:16.551] Timer tick, asking Buildbucket for the build status
[21:50:16.646] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:16.646] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:62:0) after 1m0s
[21:51:16.697] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:62:0)
[21:51:16.697] Timer tick, asking Buildbucket for the build status
[21:51:17.069] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:17.069] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:63:0) after 1m0s
[21:52:17.095] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:63:0)
[21:52:17.095] Timer tick, asking Buildbucket for the build status
[21:52:17.295] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:17.295] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:64:0) after 1m0s
[21:53:17.277] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:64:0)
[21:53:17.277] Timer tick, asking Buildbucket for the build status
[21:53:17.523] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:17.523] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:65:0) after 1m0s
[21:54:17.649] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:65:0)
[21:54:17.649] Timer tick, asking Buildbucket for the build status
[21:54:17.924] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:17.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:66:0) after 1m0s
[21:55:17.942] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:66:0)
[21:55:17.942] Timer tick, asking Buildbucket for the build status
[21:55:18.010] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:18.010] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:67:0) after 1m0s
[21:56:18.092] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:67:0)
[21:56:18.092] Timer tick, asking Buildbucket for the build status
[21:56:18.174] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:18.174] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:68:0) after 1m0s
[21:57:18.297] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:68:0)
[21:57:18.297] Timer tick, asking Buildbucket for the build status
[21:57:18.374] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:18.374] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:69:0) after 1m0s
[21:58:18.415] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:69:0)
[21:58:18.415] Timer tick, asking Buildbucket for the build status
[21:58:18.487] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:18.487] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:70:0) after 1m0s
[21:59:18.581] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:70:0)
[21:59:18.581] Timer tick, asking Buildbucket for the build status
[21:59:18.652] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:18.652] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:71:0) after 1m0s
[22:00:18.699] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:71:0)
[22:00:18.699] Timer tick, asking Buildbucket for the build status
[22:00:18.905] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:18.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:72:0) after 1m0s
[22:01:18.952] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:72:0)
[22:01:18.952] Timer tick, asking Buildbucket for the build status
[22:01:19.268] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:19.268] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:73:0) after 1m0s
[22:02:19.288] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:73:0)
[22:02:19.288] Timer tick, asking Buildbucket for the build status
[22:02:19.576] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:19.576] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:74:0) after 1m0s
[22:03:19.606] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:74:0)
[22:03:19.606] Timer tick, asking Buildbucket for the build status
[22:03:19.679] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:19.679] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:75:0) after 1m0s
[22:04:19.720] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:75:0)
[22:04:19.720] Timer tick, asking Buildbucket for the build status
[22:04:19.821] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:19.821] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:76:0) after 1m0s
[22:05:19.855] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:76:0)
[22:05:19.855] Timer tick, asking Buildbucket for the build status
[22:05:20.270] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:20.270] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:77:0) after 1m0s
[22:06:20.306] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:77:0)
[22:06:20.306] Timer tick, asking Buildbucket for the build status
[22:06:20.389] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:20.389] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:78:0) after 1m0s
[22:07:20.435] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:78:0)
[22:07:20.435] Timer tick, asking Buildbucket for the build status
[22:07:20.518] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:20.519] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:79:0) after 1m0s
[22:08:20.593] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:79:0)
[22:08:20.593] Timer tick, asking Buildbucket for the build status
[22:08:20.679] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:20.679] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:80:0) after 1m0s
[22:09:20.760] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:80:0)
[22:09:20.760] Timer tick, asking Buildbucket for the build status
[22:09:20.835] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:20.835] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:81:0) after 1m0s
[22:10:20.973] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:81:0)
[22:10:20.973] Timer tick, asking Buildbucket for the build status
[22:10:21.048] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:21.048] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:82:0) after 1m0s
[22:11:21.038] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:82:0)
[22:11:21.038] Timer tick, asking Buildbucket for the build status
[22:11:21.315] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:21.315] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:83:0) after 1m0s
[22:12:21.329] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:83:0)
[22:12:21.329] Timer tick, asking Buildbucket for the build status
[22:12:21.449] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:21.449] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:84:0) after 1m0s
[22:13:21.497] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:84:0)
[22:13:21.497] Timer tick, asking Buildbucket for the build status
[22:13:21.711] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:21.711] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:85:0) after 1m0s
[22:14:22.078] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:85:0)
[22:14:22.078] Timer tick, asking Buildbucket for the build status
[22:14:22.149] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:22.149] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:86:0) after 1m0s
[22:15:22.195] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:86:0)
[22:15:22.195] Timer tick, asking Buildbucket for the build status
[22:15:22.329] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:22.329] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:87:0) after 1m0s
[22:16:22.434] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:87:0)
[22:16:22.434] Timer tick, asking Buildbucket for the build status
[22:16:22.684] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:22.684] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:88:0) after 1m0s
[22:17:22.786] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:88:0)
[22:17:22.786] Timer tick, asking Buildbucket for the build status
[22:17:22.865] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:22.865] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:89:0) after 1m0s
[22:18:23.006] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:89:0)
[22:18:23.006] Timer tick, asking Buildbucket for the build status
[22:18:23.231] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:23.231] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:90:0) after 1m0s
[22:19:23.288] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:90:0)
[22:19:23.288] Timer tick, asking Buildbucket for the build status
[22:19:23.494] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:23.494] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:91:0) after 1m0s
[22:20:23.547] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:91:0)
[22:20:23.547] Timer tick, asking Buildbucket for the build status
[22:20:23.755] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:23.755] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:92:0) after 1m0s
[22:21:23.873] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:92:0)
[22:21:23.873] Timer tick, asking Buildbucket for the build status
[22:21:23.976] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:23.976] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:93:0) after 1m0s
[22:22:24.176] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:93:0)
[22:22:24.176] Timer tick, asking Buildbucket for the build status
[22:22:24.318] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:24.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:94:0) after 1m0s
[22:23:24.324] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:94:0)
[22:23:24.324] Timer tick, asking Buildbucket for the build status
[22:23:24.397] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:24.397] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:95:0) after 1m0s
[22:24:24.489] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:95:0)
[22:24:24.489] Timer tick, asking Buildbucket for the build status
[22:24:24.657] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:24.657] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:96:0) after 1m0s
[22:25:24.738] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:96:0)
[22:25:24.738] Timer tick, asking Buildbucket for the build status
[22:25:24.812] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:24.812] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:97:0) after 1m0s
[22:26:24.828] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:97:0)
[22:26:24.828] Timer tick, asking Buildbucket for the build status
[22:26:24.912] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:24.912] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:98:0) after 1m0s
[22:27:24.912] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:98:0)
[22:27:24.912] Timer tick, asking Buildbucket for the build status
[22:27:25.056] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:25.056] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:99:0) after 1m0s
[22:28:25.056] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:99:0)
[22:28:25.056] Timer tick, asking Buildbucket for the build status
[22:28:25.140] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:25.140] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:100:0) after 1m0s
[22:29:25.188] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:100:0)
[22:29:25.188] Timer tick, asking Buildbucket for the build status
[22:29:25.309] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:25.309] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:101:0) after 1m0s
[22:30:25.310] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:101:0)
[22:30:25.310] Timer tick, asking Buildbucket for the build status
[22:30:25.387] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:25.387] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:102:0) after 1m0s
[22:31:25.396] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:102:0)
[22:31:25.396] Timer tick, asking Buildbucket for the build status
[22:31:25.907] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:25.907] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:103:0) after 1m0s
[22:32:25.950] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:103:0)
[22:32:25.951] Timer tick, asking Buildbucket for the build status
[22:32:26.048] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:26.048] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:104:0) after 1m0s
[22:33:26.127] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:104:0)
[22:33:26.127] Timer tick, asking Buildbucket for the build status
[22:33:26.217] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:26.217] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:105:0) after 1m0s
[22:34:26.255] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:105:0)
[22:34:26.255] Timer tick, asking Buildbucket for the build status
[22:34:26.476] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:26.476] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:106:0) after 1m0s
[22:35:26.515] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:106:0)
[22:35:26.515] Timer tick, asking Buildbucket for the build status
[22:35:26.589] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:26.589] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:107:0) after 1m0s
[22:36:26.654] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:107:0)
[22:36:26.654] Timer tick, asking Buildbucket for the build status
[22:36:26.775] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:26.775] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:108:0) after 1m0s
[22:37:26.805] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:108:0)
[22:37:26.805] Timer tick, asking Buildbucket for the build status
[22:37:26.902] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:26.902] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:109:0) after 1m0s
[22:38:27.127] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:109:0)
[22:38:27.127] Timer tick, asking Buildbucket for the build status
[22:38:27.204] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:27.204] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:110:0) after 1m0s
[22:39:27.310] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:110:0)
[22:39:27.310] Timer tick, asking Buildbucket for the build status
[22:39:27.389] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:27.389] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:111:0) after 1m0s
[22:40:27.451] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:111:0)
[22:40:27.451] Timer tick, asking Buildbucket for the build status
[22:40:27.759] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:27.759] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:112:0) after 1m0s
[22:41:27.854] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:112:0)
[22:41:27.854] Timer tick, asking Buildbucket for the build status
[22:41:27.946] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:27.946] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:113:0) after 1m0s
[22:42:28.016] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:113:0)
[22:42:28.016] Timer tick, asking Buildbucket for the build status
[22:42:30.359] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:30.359] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:114:0) after 1m0s
[22:43:30.413] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:114:0)
[22:43:30.413] Timer tick, asking Buildbucket for the build status
[22:43:30.515] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:30.515] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:115:0) after 1m0s
[22:44:30.631] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:115:0)
[22:44:30.631] Timer tick, asking Buildbucket for the build status
[22:44:31.085] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:31.085] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:116:0) after 1m0s
[22:45:31.097] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:116:0)
[22:45:31.097] Timer tick, asking Buildbucket for the build status
[22:45:31.173] Build 8852570541570865280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:31.173] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018000604053921664:117:0) after 1m0s
[22:46:19.515] Received PubSub notification, asking Buildbucket for the build status
[22:46:19.584] Build 8852570541570865280: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[22:46:19.586] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1615934779181888",
"created_by": "project:chromium",
"created_ts": "1615927862537341",
"failure_reason": "BUILD_FAILURE",
"id": "8852570541570865280",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"366da1a259a761b9062786370022a7c1c459d36c\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**unit_tests** failed because of:\\n\\n- PredictionModelDownloadManagerTest.VerifiedCrxWithGoodModelFilesShouldDeleteDownloadFileButHaveContentExtracted\"}, \"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\": \"366da1a259a761b9062786370022a7c1c459d36c\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-us-central1-b-32-0-mmh4\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17823, \"clang_revision\": \"llvmorg-13-init-4330-g08a5277a-1\", \"got_angle_revision\": \"79e39478028c56b4be4e4549f8c9e53381ae48ce\", \"got_dawn_revision\": \"54586e61210cfdfc9835f81b57c794f4ac19356b\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"366da1a259a761b9062786370022a7c1c459d36c\", \"got_revision_cp\": \"refs/heads/master@{#863448}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"69b79eec6533f113920a5dd863ee2dea7d0c64e7\", \"got_v8_revision\": \"f53ac97298868f535ef84b516d80ebb6d41c67eb\", \"got_v8_revision_cp\": \"refs/heads/9.1.135@{#1}\", \"got_webrtc_revision\": \"ab6335041130fdc0365a604f49668eac9194f9d6\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33479}\", \"is_cached\": false, \"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\": \"366da1a259a761b9062786370022a7c1c459d36c\", \"swarm_hashes_refs/heads/master(at){#863448}_without_patch\": {\"absl_hardening_tests\": \"bb057ee437e127f4968c75382ad6cf620515a2ac\", \"accessibility_unittests\": \"33c28a832de40610b25d9addb8e611892dee7036\", \"angle_unittests\": \"8ff4053ad892623290b9ec5dfcaaac0f6e139d17\", \"app_shell_unittests\": \"850cce3528f11dea6868dbc12ab12000c983a23f\", \"aura_unittests\": \"28bc9cc41155f1160a8fbe2db57dd86b6c8a0fd7\", \"base_unittests\": \"cc1889ab3ce14e85d14654fda05c80f335e72a06\", \"base_util_unittests\": \"debd00f45618d75386a13c81af16c17ab16dab2f\", \"blink_common_unittests\": \"c15a4ec67174d95bc9e32725e905dfe08aa898e6\", \"blink_fuzzer_unittests\": \"6522d9c949b72d514ef068050b0976f45fbd00b1\", \"blink_heap_unittests\": \"db85b3636a6e89bc2af97d14b17f19a7ed8e1904\", \"blink_platform_unittests\": \"a0268d36183942b9380b09d11967e4c1c6ef4bef\", \"blink_unittests\": \"eada7055873cc521a3a4499f5d42e853c18b2461\", \"boringssl_crypto_tests\": \"6710f3d9ebeb9e25693d9081868e2d0ea2d411b1\", \"boringssl_ssl_tests\": \"481e04085831cc9e30671de579fa820f11aa61c4\", \"browser_switcher_bho_unittests\": \"6e4f770f23e3b65c35a7da630d097c8b558d59b7\", \"browser_tests\": \"68f096433d7a438f283648f4a97285cf5a36f6cc\", \"capture_unittests\": \"f5e75565eaf520c8b650859c8a41441615a01c7b\", \"cast_unittests\": \"8cb7f76fcb4b634d6421f91d74ab123b73a8688b\", \"cc_unittests\": \"dba3890b4f1d98609d4387ee69f5d61b2fd2f50a\", \"chrome_app_unittests\": \"afb7e92dac13638a9d98fde22930c7dd34c1933d\", \"chrome_cleaner_unittests\": \"a896a9538744ae539309f26c6f1a476eb9c80d0e\", \"chrome_elf_unittests\": \"a077d9cacdf37276f3d65a468a6afb30889ffb5d\", \"chromedriver_unittests\": \"4bbf6228b2ade47897206187b617a806e0614409\", \"color_unittests\": \"5c65350a6f723789f9fe128303c400195446ca5a\", \"components_browsertests\": \"4075309ebaa574394b7a6e3c4262b32966645cd8\", \"components_unittests\": \"22822d88445104a083cc0068f7dfe13d5f570ec9\", \"compositor_unittests\": \"f37e8c67bdb76846b35e12ce590d5888b49f8912\", \"content_browsertests\": \"91610f7d84448175ee18917076ba57eaef102ae0\", \"content_unittests\": \"bd5d60a8c2b460625637d120035f31de73ce88b4\", \"courgette_unittests\": \"cc7e495d7b7e8c943ab2a9a0ef1622514646cc2c\", \"crashpad_tests\": \"c84cdc425d3efa98008f5a5e5d40e5146112d5ae\", \"cronet_tests\": \"05f8999240ecf4c4d1e5745ceb8a15e8f3e144f7\", \"cronet_unittests\": \"89062abb821c239904125cfa5d106fd17609b108\", \"crypto_unittests\": \"1c3e28cb7a589ea5067532c26d416ae4b77369f2\", \"delayloads_unittests\": \"038e95a24eb189c5b7ab3e2d86cb426d5c52226c\", \"device_unittests\": \"224af902bbd9e166efc57474008ec43d44fab998\", \"display_unittests\": \"414c7cad2978a79748ea9f32dc6835ebaab67829\", \"elevation_service_unittests\": \"6902758c58c26a0acc698843cbdf1ea899285355\", \"events_unittests\": \"92f65965703099c3ee08f18f697250719f82baa5\", \"extensions_browsertests\": \"6c6298368ec3c1f63468dc3739272ca9080f0dbb\", \"extensions_unittests\": \"25830363feafc401439b1b245bff4ade6f3151ea\", \"filesystem_service_unittests\": \"0fd7663de5fd95abfd0a75bdf774c1c325a35741\", \"gcm_unit_tests\": \"86b8630bfa66702a4a2b01ceb72f8a2eaf2e2905\", \"gcp_unittests\": \"78d4ff3d75ffedc3833ee0c9b9f4aa0e71c51c5d\", \"gfx_unittests\": \"4ce2730f03b97689c853cc4c49da95ab6262f2fa\", \"gin_unittests\": \"8b228855992f65f03f6a682be3ecb0a21c4ce817\", \"google_apis_unittests\": \"1a322b4b42bb16b08f0a9e806625b7cc4c97c388\", \"gpu_unittests\": \"6b44c9ebde079f149589b895219770a8dd6e2cbb\", \"gwp_asan_unittests\": \"2d2a0831f0860924306b23dd60178344a01693f3\", \"headless_browsertests\": \"a0163cb16125eb76f42dd469cb6725d3085bf196\", \"headless_unittests\": \"6a70ee12f25dc3a45776bc74355e847e27b4c085\", \"install_static_unittests\": \"529cb5bd175ef67b5b747516bef3081c7788cb22\", \"installer_util_unittests\": \"bf8f15ec6e06577c4e0c687423bc597257261a73\", \"interactive_ui_tests\": \"3510211c0b06a85a23c773b0b31c5c4f246991c4\", \"ipc_tests\": \"f5177a8fd6c0e308f8f2d30595ddf9d85654c266\", \"jingle_unittests\": \"8f479ad981ca3671b69428c134110a232752c873\", \"latency_unittests\": \"e44e47988cb10f1aac2762d42f7595c8b0ef06cb\", \"libjingle_xmpp_unittests\": \"652a4983c812ccbf8d52a0400f47763efe2c9d74\", \"liburlpattern_unittests\": \"7dc7979ec743d8eba379f3b8865100f5ac8876b6\", \"media_blink_unittests\": \"a0596308136e0b3d9ce3016c6fb5fbc0e120de0e\", \"media_unittests\": \"d0e60b0e19213c6f9b564f3f23c350bdbeb8d043\", \"message_center_unittests\": \"27b243135dc1369a911ddf399880048a08722119\", \"midi_unittests\": \"62282312a1d209252d04ff4683050380ff35ebc6\", \"mojo_core_unittests\": \"36740e7631701cff16749a895cd5c633e2ba3d35\", \"mojo_unittests\": \"800ec62b57377e60051bcc5b6cd13b4b8606bfd0\", \"nacl_loader_unittests\": \"3530708861d25e8a397a09f8517a10c18e5df754\", \"native_theme_unittests\": \"41b7973a03e3444e107a3bf26afbc1badc4f2fe1\", \"net_unittests\": \"887bdc8e873f3168b44e681f90b65e55dd242e95\", \"notification_helper_unittests\": \"bb46b89465a7711d8900df7a4f36c39ae899cec7\", \"pdf_unittests\": \"5b1d7e5cb3d98b852ee9cc842c6985ae26bceb11\", \"perfetto_unittests\": \"7165c112099608c195b5143df270ba7e40781445\", \"ppapi_unittests\": \"65d5c51b46a81ffcde19a8fde61dfcec67461800\", \"printing_unittests\": \"1d89ab8c55702c668a46e6fcda8348af71fa1507\", \"remoting_unittests\": \"2cb62748011daf761c3afeae4d58626b8f30a232\", \"sbox_integration_tests\": \"30944ad850602ed6faae36a4967f91774edd3ae9\", \"sbox_unittests\": \"73894c01ea206a23da50a9358c5ed74771b8ba84\", \"sbox_validation_tests\": \"b0ecd0f036d759fecdc61e2f66d413e525dd4d72\", \"service_manager_unittests\": \"6909ea58f948f2d6b0dcf20a88ebbca5609269eb\", \"services_unittests\": \"736391b906d0edad1be287e291d416ad22685265\", \"setup_unittests\": \"ccb168048e653b38be6c7e3c1640b7f5a7d6110e\", \"shell_dialogs_unittests\": \"78876cf2fc17ea92b83ecf1adb8e2c9f7c85742f\", \"skia_unittests\": \"c72ad08d5a8511fcfafa784f4ea2d56e65fd5673\", \"snapshot_unittests\": \"44ac221e6c7ebfdb8a769e7c2a1c58252ba1e782\", \"sql_unittests\": \"66eb3f0814e534639fa333c37bc060a288ed4641\", \"storage_unittests\": \"dead6c57477e98fb93841cdd34bcd1e2a396a9b0\", \"sync_integration_tests\": \"82570dfc94f6c6b53b6986303d7187001c5800b4\", \"traffic_annotation_auditor_unittests\": \"7100e10805bd41041950947c57116f803c1ad8bb\", \"ui_base_unittests\": \"6525819b99edec968cca57407cdda2de0129475c\", \"ui_touch_selection_unittests\": \"eedd5c88b6065254a6172a6d8401a47d44ee0c7a\", \"unit_tests\": \"8844113a36380a18b4655e666c620483f0d7c2ea\", \"updater_tests\": \"fa28a5ec046dcd7e49c4a2adb41eb1997039b881\", \"url_unittests\": \"4f2def63006befd318ef12f37c3f886176f557f9\", \"views_unittests\": \"5a90f2791af91ea07c072b4593f219c959b9bbfb\", \"viz_unittests\": \"99a2f64ab83841a9319363a11f356c8ca8b9436f\", \"vr_common_unittests\": \"0cee473a9f8b7d140af827c35e82ad6a4f634495\", \"vr_pixeltests\": \"d88344b3108d22b2923bea12a6d545cfe8f1449e\", \"weblayer_browsertests\": \"c9f70234e1f0f5e76a07bbad0b1199d9fcc4c96e\", \"weblayer_unittests\": \"20144f72029caf502aff0fb54346d130b5ee7475\", \"wm_unittests\": \"c2c099c95380f59d9d5cb884418af3671a6004fd\", \"wtf_unittests\": \"4bc3bc04d5549b8ea77d60469ac534c956ca1a31\", \"zlib_unittests\": \"8cf0c1101fa913ade7b5b2a57e57a5e6c670e1d4\", \"zucchini_unittests\": \"4356db61b6de542af8c00b739973ab403c4f2822\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_27a86a15ec8feb386a5b02e9d1f544a49194bc134f7348519b0bd6cd38b20371_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-us-central1-b-32-0-mmh4\"], \"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-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**unit_tests** failed because of:\\n\\n- PredictionModelDownloadManagerTest.VerifiedCrxWithGoodModelFilesShouldDeleteDownloadFileButHaveContentExtracted\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1615928153399950",
"status": "COMPLETED",
"status_changed_ts": "1615928153688964",
"tags": [
"build_address:luci.chromium.ci/win-asan/17823",
"builder:win-asan",
"buildset:commit/git/366da1a259a761b9062786370022a7c1c459d36c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/366da1a259a761b9062786370022a7c1c459d36c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018000604053921664",
"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/8852570541570865280/+/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:52559feff8b16510",
"user_agent:luci-scheduler"
],
"updated_ts": "1615934779182277",
"url": "https://ci.chromium.org/b/8852570541570865280",
"utcnow_ts": "1615934779572413"
}
}
[22:46:19.587] Invocation finished in 1h55m18.801570762s with status FAILED