[08:03:59.683] New invocation is queued and will start shortly
[08:04:01.163] Starting the invocation (attempt 1)
[08:04:01.224] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:04:01.225] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:04:01.225] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019860800027889232",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"b90646417bd3e0106689c8eccc8d7ad9a91f1348\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9019860800027889232",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/b90646417bd3e0106689c8eccc8d7ad9a91f1348",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b90646417bd3e0106689c8eccc8d7ad9a91f1348",
"gitiles_ref:refs/heads/master"
]
}
[08:04:02.823] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614153841353196",
"id": "8854430737607773456",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b90646417bd3e0106689c8eccc8d7ad9a91f1348\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614153842524368",
"tags": [
"build_address:luci.chromium.ci/win-asan/17504",
"builder:win-asan",
"buildset:commit/git/b90646417bd3e0106689c8eccc8d7ad9a91f1348",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b90646417bd3e0106689c8eccc8d7ad9a91f1348",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019860800027889232",
"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/8854430737607773456/+/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": "1614153842524685",
"url": "https://ci.chromium.org/b/8854430737607773456",
"utcnow_ts": "1614153842803958"
}
}
[08:04:02.823] Task URL: https://ci.chromium.org/b/8854430737607773456
[08:04:02.823] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:2:0) after 1m0s
[08:05:02.858] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:2:0)
[08:05:02.858] Timer tick, asking Buildbucket for the build status
[08:05:02.964] Build 8854430737607773456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:05:02.964] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:3:0) after 1m0s
[08:06:02.980] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:3:0)
[08:06:02.980] Timer tick, asking Buildbucket for the build status
[08:06:03.079] Build 8854430737607773456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:06:03.080] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:4:0) after 1m0s
[08:06:48.028] Received PubSub notification, asking Buildbucket for the build status
[08:06:48.095] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:03.124] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:4:0)
[08:07:03.124] Timer tick, asking Buildbucket for the build status
[08:07:03.330] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:03.330] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:6:0) after 1m0s
[08:08:03.369] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:6:0)
[08:08:03.369] Timer tick, asking Buildbucket for the build status
[08:08:03.451] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:03.451] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:7:0) after 1m0s
[08:09:03.464] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:7:0)
[08:09:03.464] Timer tick, asking Buildbucket for the build status
[08:09:03.534] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:03.534] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:8:0) after 1m0s
[08:10:03.670] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:8:0)
[08:10:03.670] Timer tick, asking Buildbucket for the build status
[08:10:03.776] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:03.776] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:9:0) after 1m0s
[08:11:03.825] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:9:0)
[08:11:03.825] Timer tick, asking Buildbucket for the build status
[08:11:03.890] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:03.890] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:10:0) after 1m0s
[08:12:03.908] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:10:0)
[08:12:03.908] Timer tick, asking Buildbucket for the build status
[08:12:03.978] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:03.978] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:11:0) after 1m0s
[08:13:04.014] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:11:0)
[08:13:04.014] Timer tick, asking Buildbucket for the build status
[08:13:04.108] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:04.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:12:0) after 1m0s
[08:14:04.240] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:12:0)
[08:14:04.240] Timer tick, asking Buildbucket for the build status
[08:14:04.364] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:04.364] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:13:0) after 1m0s
[08:15:04.447] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:13:0)
[08:15:04.447] Timer tick, asking Buildbucket for the build status
[08:15:04.593] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:04.593] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:14:0) after 1m0s
[08:16:04.657] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:14:0)
[08:16:04.657] Timer tick, asking Buildbucket for the build status
[08:16:04.745] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:04.745] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:15:0) after 1m0s
[08:17:04.795] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:15:0)
[08:17:04.795] Timer tick, asking Buildbucket for the build status
[08:17:04.877] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:04.877] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:16:0) after 1m0s
[08:18:04.889] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:16:0)
[08:18:04.889] Timer tick, asking Buildbucket for the build status
[08:18:04.960] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:04.960] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:17:0) after 1m0s
[08:19:05.070] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:17:0)
[08:19:05.070] Timer tick, asking Buildbucket for the build status
[08:19:05.256] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:05.256] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:18:0) after 1m0s
[08:20:05.311] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:18:0)
[08:20:05.311] Timer tick, asking Buildbucket for the build status
[08:20:05.409] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:05.409] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:19:0) after 1m0s
[08:21:05.532] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:19:0)
[08:21:05.532] Timer tick, asking Buildbucket for the build status
[08:21:05.714] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:05.714] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:20:0) after 1m0s
[08:22:05.793] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:20:0)
[08:22:05.793] Timer tick, asking Buildbucket for the build status
[08:22:05.869] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:05.869] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:21:0) after 1m0s
[08:23:05.943] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:21:0)
[08:23:05.943] Timer tick, asking Buildbucket for the build status
[08:23:06.147] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:06.147] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:22:0) after 1m0s
[08:24:06.270] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:22:0)
[08:24:06.270] Timer tick, asking Buildbucket for the build status
[08:24:06.496] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:06.496] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:23:0) after 1m0s
[08:25:06.550] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:23:0)
[08:25:06.550] Timer tick, asking Buildbucket for the build status
[08:25:06.623] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:06.623] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:24:0) after 1m0s
[08:26:06.647] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:24:0)
[08:26:06.647] Timer tick, asking Buildbucket for the build status
[08:26:06.754] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:06.754] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:25:0) after 1m0s
[08:27:06.773] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:25:0)
[08:27:06.773] Timer tick, asking Buildbucket for the build status
[08:27:06.852] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:06.852] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:26:0) after 1m0s
[08:28:06.877] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:26:0)
[08:28:06.877] Timer tick, asking Buildbucket for the build status
[08:28:07.068] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:07.068] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:27:0) after 1m0s
[08:29:07.096] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:27:0)
[08:29:07.096] Timer tick, asking Buildbucket for the build status
[08:29:07.408] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:07.408] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:28:0) after 1m0s
[08:30:07.421] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:28:0)
[08:30:07.421] Timer tick, asking Buildbucket for the build status
[08:30:07.609] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:07.609] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:29:0) after 1m0s
[08:31:07.703] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:29:0)
[08:31:07.703] Timer tick, asking Buildbucket for the build status
[08:31:08.055] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:08.055] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:30:0) after 1m0s
[08:32:08.072] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:30:0)
[08:32:08.072] Timer tick, asking Buildbucket for the build status
[08:32:08.149] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:08.149] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:31:0) after 1m0s
[08:33:08.362] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:31:0)
[08:33:08.362] Timer tick, asking Buildbucket for the build status
[08:33:08.433] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:08.433] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:32:0) after 1m0s
[08:34:08.493] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:32:0)
[08:34:08.493] Timer tick, asking Buildbucket for the build status
[08:34:08.866] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:08.866] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:33:0) after 1m0s
[08:35:08.884] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:33:0)
[08:35:08.884] Timer tick, asking Buildbucket for the build status
[08:35:08.970] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:08.970] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:34:0) after 1m0s
[08:36:08.975] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:34:0)
[08:36:08.975] Timer tick, asking Buildbucket for the build status
[08:36:09.098] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:09.098] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:35:0) after 1m0s
[08:37:09.150] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:35:0)
[08:37:09.150] Timer tick, asking Buildbucket for the build status
[08:37:09.232] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:09.232] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:36:0) after 1m0s
[08:38:09.216] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:36:0)
[08:38:09.216] Timer tick, asking Buildbucket for the build status
[08:38:09.294] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:09.294] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:37:0) after 1m0s
[08:39:09.430] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:37:0)
[08:39:09.430] Timer tick, asking Buildbucket for the build status
[08:39:09.519] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:09.519] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:38:0) after 1m0s
[08:40:09.549] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:38:0)
[08:40:09.549] Timer tick, asking Buildbucket for the build status
[08:40:09.631] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:09.631] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:39:0) after 1m0s
[08:41:09.746] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:39:0)
[08:41:09.746] Timer tick, asking Buildbucket for the build status
[08:41:09.908] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:09.908] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:40:0) after 1m0s
[08:42:09.995] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:40:0)
[08:42:09.995] Timer tick, asking Buildbucket for the build status
[08:42:10.184] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:10.184] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:41:0) after 1m0s
[08:43:10.237] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:41:0)
[08:43:10.237] Timer tick, asking Buildbucket for the build status
[08:43:10.303] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:10.303] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:42:0) after 1m0s
[08:44:10.318] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:42:0)
[08:44:10.318] Timer tick, asking Buildbucket for the build status
[08:44:10.600] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:10.600] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:43:0) after 1m0s
[08:45:10.677] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:43:0)
[08:45:10.677] Timer tick, asking Buildbucket for the build status
[08:45:10.974] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:10.979] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:44:0) after 1m0s
[08:46:10.998] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:44:0)
[08:46:10.998] Timer tick, asking Buildbucket for the build status
[08:46:11.071] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:11.071] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:45:0) after 1m0s
[08:47:11.128] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:45:0)
[08:47:11.128] Timer tick, asking Buildbucket for the build status
[08:47:11.202] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:11.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:46:0) after 1m0s
[08:48:11.205] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:46:0)
[08:48:11.205] Timer tick, asking Buildbucket for the build status
[08:48:11.431] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:11.431] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:47:0) after 1m0s
[08:49:11.477] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:47:0)
[08:49:11.477] Timer tick, asking Buildbucket for the build status
[08:49:11.580] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:11.580] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:48:0) after 1m0s
[08:50:11.599] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:48:0)
[08:50:11.599] Timer tick, asking Buildbucket for the build status
[08:50:11.738] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:11.738] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:49:0) after 1m0s
[08:51:11.771] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:49:0)
[08:51:11.771] Timer tick, asking Buildbucket for the build status
[08:51:12.048] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:12.048] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:50:0) after 1m0s
[08:52:12.075] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:50:0)
[08:52:12.075] Timer tick, asking Buildbucket for the build status
[08:52:12.158] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:12.158] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:51:0) after 1m0s
[08:53:12.200] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:51:0)
[08:53:12.200] Timer tick, asking Buildbucket for the build status
[08:53:12.271] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:12.271] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:52:0) after 1m0s
[08:54:12.481] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:52:0)
[08:54:12.481] Timer tick, asking Buildbucket for the build status
[08:54:12.578] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:12.578] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:53:0) after 1m0s
[08:55:12.754] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:53:0)
[08:55:12.754] Timer tick, asking Buildbucket for the build status
[08:55:12.904] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:12.904] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:54:0) after 1m0s
[08:56:15.486] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:54:0)
[08:56:15.486] Timer tick, asking Buildbucket for the build status
[08:56:15.765] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:15.765] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:55:0) after 1m0s
[08:57:15.995] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:55:0)
[08:57:15.995] Timer tick, asking Buildbucket for the build status
[08:57:16.303] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:16.303] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:56:0) after 1m0s
[08:58:16.325] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:56:0)
[08:58:16.325] Timer tick, asking Buildbucket for the build status
[08:58:16.538] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:16.538] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:57:0) after 1m0s
[08:59:16.550] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:57:0)
[08:59:16.550] Timer tick, asking Buildbucket for the build status
[08:59:16.653] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:16.653] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:58:0) after 1m0s
[09:00:16.681] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:58:0)
[09:00:16.681] Timer tick, asking Buildbucket for the build status
[09:00:16.869] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:16.869] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:59:0) after 1m0s
[09:01:16.963] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:59:0)
[09:01:16.963] Timer tick, asking Buildbucket for the build status
[09:01:17.217] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:17.217] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:60:0) after 1m0s
[09:02:17.287] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:60:0)
[09:02:17.287] Timer tick, asking Buildbucket for the build status
[09:02:17.375] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:17.375] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:61:0) after 1m0s
[09:03:17.396] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:61:0)
[09:03:17.396] Timer tick, asking Buildbucket for the build status
[09:03:17.466] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:17.466] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:62:0) after 1m0s
[09:04:17.476] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:62:0)
[09:04:17.476] Timer tick, asking Buildbucket for the build status
[09:04:17.584] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:17.584] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:63:0) after 1m0s
[09:05:17.598] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:63:0)
[09:05:17.598] Timer tick, asking Buildbucket for the build status
[09:05:17.676] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:17.676] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:64:0) after 1m0s
[09:06:17.698] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:64:0)
[09:06:17.698] Timer tick, asking Buildbucket for the build status
[09:06:17.780] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:17.780] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:65:0) after 1m0s
[09:07:17.799] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:65:0)
[09:07:17.799] Timer tick, asking Buildbucket for the build status
[09:07:17.947] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:17.947] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:66:0) after 1m0s
[09:08:17.987] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:66:0)
[09:08:17.987] Timer tick, asking Buildbucket for the build status
[09:08:18.202] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:18.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:67:0) after 1m0s
[09:09:18.291] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:67:0)
[09:09:18.291] Timer tick, asking Buildbucket for the build status
[09:09:18.442] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:18.442] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:68:0) after 1m0s
[09:10:18.444] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:68:0)
[09:10:18.444] Timer tick, asking Buildbucket for the build status
[09:10:18.517] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:18.517] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:69:0) after 1m0s
[09:11:18.566] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:69:0)
[09:11:18.566] Timer tick, asking Buildbucket for the build status
[09:11:18.706] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:18.706] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:70:0) after 1m0s
[09:12:18.736] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:70:0)
[09:12:18.736] Timer tick, asking Buildbucket for the build status
[09:12:18.925] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:18.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:71:0) after 1m0s
[09:13:18.955] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:71:0)
[09:13:18.955] Timer tick, asking Buildbucket for the build status
[09:13:19.030] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:19.030] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:72:0) after 1m0s
[09:14:19.059] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:72:0)
[09:14:19.059] Timer tick, asking Buildbucket for the build status
[09:14:19.561] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:19.561] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:73:0) after 1m0s
[09:15:19.734] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:73:0)
[09:15:19.734] Timer tick, asking Buildbucket for the build status
[09:15:19.811] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:19.811] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:74:0) after 1m0s
[09:16:19.875] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:74:0)
[09:16:19.875] Timer tick, asking Buildbucket for the build status
[09:16:19.957] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:19.957] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:75:0) after 1m0s
[09:17:20.051] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:75:0)
[09:17:20.051] Timer tick, asking Buildbucket for the build status
[09:17:20.134] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:20.134] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:76:0) after 1m0s
[09:18:20.140] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:76:0)
[09:18:20.140] Timer tick, asking Buildbucket for the build status
[09:18:20.424] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:20.424] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:77:0) after 1m0s
[09:19:20.514] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:77:0)
[09:19:20.514] Timer tick, asking Buildbucket for the build status
[09:19:20.615] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:20.615] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:78:0) after 1m0s
[09:20:20.656] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:78:0)
[09:20:20.656] Timer tick, asking Buildbucket for the build status
[09:20:20.748] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:20.748] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:79:0) after 1m0s
[09:21:20.762] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:79:0)
[09:21:20.762] Timer tick, asking Buildbucket for the build status
[09:21:20.849] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:20.849] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:80:0) after 1m0s
[09:22:20.955] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:80:0)
[09:22:20.955] Timer tick, asking Buildbucket for the build status
[09:22:21.205] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:21.205] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:81:0) after 1m0s
[09:23:21.258] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:81:0)
[09:23:21.258] Timer tick, asking Buildbucket for the build status
[09:23:21.356] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:21.356] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:82:0) after 1m0s
[09:24:21.398] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:82:0)
[09:24:21.398] Timer tick, asking Buildbucket for the build status
[09:24:21.548] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:21.548] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:83:0) after 1m0s
[09:25:21.608] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:83:0)
[09:25:21.608] Timer tick, asking Buildbucket for the build status
[09:25:21.686] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:21.686] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:84:0) after 1m0s
[09:26:22.083] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:84:0)
[09:26:22.083] Timer tick, asking Buildbucket for the build status
[09:26:22.178] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:22.178] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:85:0) after 1m0s
[09:27:22.289] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:85:0)
[09:27:22.289] Timer tick, asking Buildbucket for the build status
[09:27:22.558] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:22.558] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:86:0) after 1m0s
[09:28:22.638] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:86:0)
[09:28:22.638] Timer tick, asking Buildbucket for the build status
[09:28:22.715] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:22.715] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:87:0) after 1m0s
[09:29:23.009] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:87:0)
[09:29:23.009] Timer tick, asking Buildbucket for the build status
[09:29:23.146] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:23.146] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:88:0) after 1m0s
[09:30:23.151] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:88:0)
[09:30:23.151] Timer tick, asking Buildbucket for the build status
[09:30:23.450] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:23.450] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:89:0) after 1m0s
[09:31:23.480] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:89:0)
[09:31:23.480] Timer tick, asking Buildbucket for the build status
[09:31:23.551] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:23.551] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:90:0) after 1m0s
[09:32:23.598] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:90:0)
[09:32:23.598] Timer tick, asking Buildbucket for the build status
[09:32:23.683] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:23.683] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:91:0) after 1m0s
[09:33:23.753] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:91:0)
[09:33:23.753] Timer tick, asking Buildbucket for the build status
[09:33:23.844] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:23.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:92:0) after 1m0s
[09:34:24.190] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:92:0)
[09:34:24.190] Timer tick, asking Buildbucket for the build status
[09:34:24.521] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:24.521] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:93:0) after 1m0s
[09:35:24.603] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:93:0)
[09:35:24.603] Timer tick, asking Buildbucket for the build status
[09:35:24.686] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:24.686] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:94:0) after 1m0s
[09:36:24.721] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:94:0)
[09:36:24.721] Timer tick, asking Buildbucket for the build status
[09:36:24.878] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:24.878] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:95:0) after 1m0s
[09:37:24.896] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:95:0)
[09:37:24.896] Timer tick, asking Buildbucket for the build status
[09:37:25.062] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:25.062] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:96:0) after 1m0s
[09:38:25.084] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:96:0)
[09:38:25.084] Timer tick, asking Buildbucket for the build status
[09:38:25.175] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:25.175] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:97:0) after 1m0s
[09:39:25.197] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:97:0)
[09:39:25.197] Timer tick, asking Buildbucket for the build status
[09:39:25.293] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:25.293] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:98:0) after 1m0s
[09:40:25.321] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:98:0)
[09:40:25.321] Timer tick, asking Buildbucket for the build status
[09:40:25.519] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:25.519] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:99:0) after 1m0s
[09:41:25.553] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:99:0)
[09:41:25.553] Timer tick, asking Buildbucket for the build status
[09:41:25.637] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:25.637] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:100:0) after 1m0s
[09:42:25.663] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:100:0)
[09:42:25.664] Timer tick, asking Buildbucket for the build status
[09:42:25.747] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:25.747] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:101:0) after 1m0s
[09:43:27.664] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:101:0)
[09:43:27.664] Timer tick, asking Buildbucket for the build status
[09:43:27.869] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:27.869] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:102:0) after 1m0s
[09:44:27.939] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:102:0)
[09:44:27.939] Timer tick, asking Buildbucket for the build status
[09:44:28.109] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:28.109] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:103:0) after 1m0s
[09:45:28.117] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:103:0)
[09:45:28.117] Timer tick, asking Buildbucket for the build status
[09:45:28.198] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:28.198] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:104:0) after 1m0s
[09:46:28.218] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:104:0)
[09:46:28.218] Timer tick, asking Buildbucket for the build status
[09:46:28.328] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:28.328] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:105:0) after 1m0s
[09:47:28.388] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:105:0)
[09:47:28.388] Timer tick, asking Buildbucket for the build status
[09:47:29.037] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:29.037] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:106:0) after 1m0s
[09:48:29.219] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:106:0)
[09:48:29.219] Timer tick, asking Buildbucket for the build status
[09:48:29.348] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:29.348] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:107:0) after 1m0s
[09:49:29.482] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:107:0)
[09:49:29.482] Timer tick, asking Buildbucket for the build status
[09:49:29.571] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:29.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:108:0) after 1m0s
[09:50:29.676] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:108:0)
[09:50:29.676] Timer tick, asking Buildbucket for the build status
[09:50:29.764] Build 8854430737607773456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:29.764] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019860800027889232:109:0) after 1m0s
[09:51:26.982] Received PubSub notification, asking Buildbucket for the build status
[09:51:27.065] Build 8854430737607773456: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[09:51:27.065] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614160286633383",
"created_by": "project:chromium",
"created_ts": "1614153841353196",
"failure_reason": "BUILD_FAILURE",
"id": "8854430737607773456",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b90646417bd3e0106689c8eccc8d7ad9a91f1348\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**unit_tests** failed because of:\\n\\n- ContextualNotificationPermissionUiSelectorTest.PrefAndSafeBrowsingCombinations\"}, \"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\": \"b90646417bd3e0106689c8eccc8d7ad9a91f1348\", \"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-3-xpf4\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17504, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-2\", \"got_angle_revision\": \"f19f319a59c83d9ccdce08a3b41024cfc997fd8d\", \"got_dawn_revision\": \"d67544797e54d3866f378c2a7c05181db552f125\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"b90646417bd3e0106689c8eccc8d7ad9a91f1348\", \"got_revision_cp\": \"refs/heads/master@{#857064}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"22186f076bf3bbe1a818792bde93b297e3773f7e\", \"got_v8_revision\": \"2fa4745e7f27e89e6287e7dd2fbe15b2b23ec7f0\", \"got_v8_revision_cp\": \"refs/heads/9.0.247@{#1}\", \"got_webrtc_revision\": \"d0844a80deaab28d7df8e5dff9f71e6ab6919025\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33323}\", \"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\": \"b90646417bd3e0106689c8eccc8d7ad9a91f1348\", \"swarm_hashes_refs/heads/master(at){#857064}_without_patch\": {\"absl_hardening_tests\": \"c09b678be0ed806b6f0edbcdb13730c9d2b429e2\", \"accessibility_unittests\": \"0b4e4daa318fe4bd2564b3c3e21301ef9bffbb71\", \"angle_unittests\": \"15f5cc3f6ad676863436f241556bf7a3a41f2473\", \"app_shell_unittests\": \"b3cf8b9816603c81fbf75c7236debed9250f9a6f\", \"aura_unittests\": \"a8a95daf441dbff502ce47c27e3bc4c046f676cb\", \"base_unittests\": \"9cc00c3702d492be8ca51cec2c6752478a9ae81b\", \"base_util_unittests\": \"a941540828cdc9294a8f290e1136da7297fe21f9\", \"blink_common_unittests\": \"0a6593be701351cec75489be278ac9ee87a53306\", \"blink_fuzzer_unittests\": \"43afe0c4e434ff5ab9db0c08547d80203eb907f1\", \"blink_heap_unittests\": \"de5a6f5ee0fcad63336b21fcf909200db8b5ab2f\", \"blink_platform_unittests\": \"3c49503a6d550e07ea8e5d43f94bfe21dde181f8\", \"blink_unittests\": \"f2299b7fadf0d554ea9ea3380b26e1eff4ce99f6\", \"boringssl_crypto_tests\": \"eaffa5d12adf692bdb7e8fa939898fe908aba020\", \"boringssl_ssl_tests\": \"b90a5dcfcf288c06f1c7b510298b340cccdb39bf\", \"browser_switcher_bho_unittests\": \"502055f7ba822262a52281d357953ba709b5880c\", \"browser_tests\": \"4ec7dfee35c98bf140d848c5776ea0acc23589f6\", \"capture_unittests\": \"e3803cbd55982730cfa8604fb2c6d1f36e7294c4\", \"cast_unittests\": \"405250fa79ffb522fe2ed657fb061b9d3f6c0090\", \"cc_unittests\": \"319c36b68fe8d4a769392634f316647a6c1879e8\", \"chrome_app_unittests\": \"5f49a34e17ac71e0ff7c42339152251dcd693a78\", \"chrome_cleaner_unittests\": \"a1afdaa3f619c5a4fb82bcc0ae8d4c21b96a3e7f\", \"chrome_elf_unittests\": \"99a4386b173b8c9d04716d9843bb87d984a28e06\", \"chromedriver_unittests\": \"5e31e73563dca96c8ca34a658a05beea10fffa48\", \"color_unittests\": \"129438c4151b0d3345c0909847d5fb33ae5bceb5\", \"components_browsertests\": \"939601f0711fed0739b398296d681e3d0759fabb\", \"components_unittests\": \"08574f1cc4abbbd3a9855e50cdaad55da88c7873\", \"compositor_unittests\": \"2e673a3ed677b1fb5b630c1b57bfbc83b74c8db1\", \"content_browsertests\": \"84d4b4725a42efde71067c0db976a293f38563db\", \"content_unittests\": \"9cfd0e664396009c08de5a8327c363a203b753b2\", \"courgette_unittests\": \"5e5f411c1842bcda1df7c4881aeac1fe9c2d6db5\", \"crashpad_tests\": \"02299e5d3d0577ee61f53495d06d069e4d20744e\", \"cronet_tests\": \"9f3067284dba30c5ced974142d0712c5e3e44e10\", \"cronet_unittests\": \"8c5e5b3becc861e73cb293f96d95bbfcabd74eb8\", \"crypto_unittests\": \"77790055e53a525dc18193822ac154df6e0e7705\", \"delayloads_unittests\": \"f55bf284c4f54344b27834c6640f62635f5e3a1a\", \"device_unittests\": \"d41a94cf677c61d207dd0e939ccf2a62d8361d7a\", \"display_unittests\": \"15a6d10eacf3e4ff6659be27211717df47977bf2\", \"elevation_service_unittests\": \"34b5cfe6ebc1367dc7ab457f12eb117b98fefdfb\", \"events_unittests\": \"b5c9b398d88321dd77dbef3ac7db5614d4e1e8e6\", \"extensions_browsertests\": \"b6760800720fa17e3cd8ab5421a65834d3f6f4ec\", \"extensions_unittests\": \"38507378f2ebb900b57c0058994567b2af19c39c\", \"filesystem_service_unittests\": \"ae9c89254b6e728bee60d3445690aead57840f7e\", \"gcm_unit_tests\": \"ae0cd9cab372717e798f461102d6090adad9a417\", \"gcp_unittests\": \"957370137085462575a4e8b97f565fa1e61576d7\", \"gfx_unittests\": \"db578d620a77581c66180a7a4b8ca5f730b952bc\", \"gin_unittests\": \"d46ad5d3083eee545db820587a0eff518e0ee22a\", \"google_apis_unittests\": \"2d9a7db181f8a19ea420c761f021fcbc2f8ffa23\", \"gpu_unittests\": \"46abe85f256a0b6413a15b03a6b5b67ca2c528cc\", \"gwp_asan_unittests\": \"93e7dedd71ad06ca4e9731928c85687ae8303eb8\", \"headless_browsertests\": \"5ee953c057b4f40310e63bd7b733311fc1cfc9c1\", \"headless_unittests\": \"f20f42658bb8dfb4b239547f9d36844262560711\", \"install_static_unittests\": \"f1d7bf40b1885a60a503850956ea209b58377dab\", \"installer_util_unittests\": \"f49552a4b358404b5902a3dc869cb1646c0c94b4\", \"interactive_ui_tests\": \"0ac7a7f5423ca2e1f02d239e3c8aa8dfd93ed2dc\", \"ipc_tests\": \"512e3afbc3d5bb568c32f2e701ae45b3e48efa36\", \"jingle_unittests\": \"d8d8e29f465e8c62a4af6509f8f6a0076f68cd21\", \"latency_unittests\": \"b024b67b14ad6cb01389fe60ac243b946a5f6180\", \"libjingle_xmpp_unittests\": \"cf811a9274454b820668f44982206f2bb6c5c704\", \"liburlpattern_unittests\": \"f28715e14064e19e541ca80fce0f481a04959edb\", \"media_blink_unittests\": \"823af3e867f94d809b824c0282f790695cf3ca72\", \"media_unittests\": \"256d8239694c46d1a7c7be1cc5bfd05831abd6bd\", \"message_center_unittests\": \"4237d81f266d36fd818c37cbf33a8b0121d72964\", \"midi_unittests\": \"c90baffa09988920115649f36f5f625caaefa5e4\", \"mojo_core_unittests\": \"d89a4400c4185bf9aad33000eee2b4b2eeb3fe06\", \"mojo_unittests\": \"34ed1ba2232b377a6cc0aa2f4af38dbec6287f22\", \"nacl_loader_unittests\": \"338cea2c781ed419e92b796e009e018c1166ae3b\", \"native_theme_unittests\": \"e5ac3709432bb61fa4e52bd358e36cefc71e5f85\", \"net_unittests\": \"6ad41f1f0bb6366a285c1962fb4a97d6545bc4bd\", \"notification_helper_unittests\": \"12ad54f73f5c67da7f68760d7cb66051f8236d87\", \"pdf_unittests\": \"839995173829b366e89b742e7ad96e5ebf3df626\", \"perfetto_unittests\": \"69e39dd837ab596b3cd4b1694963355a1f38279b\", \"ppapi_unittests\": \"807eec39f0e42b51c17bc07c60ae944b71e67afc\", \"printing_unittests\": \"a76c0265a8f68661e7f0ae5970693fc3910faaa2\", \"remoting_unittests\": \"fd43dd6019ef4954d60ecac83b1759ec3c5689c6\", \"sbox_integration_tests\": \"7f7d39e2bac7ce89ef60f1e0a4bd9250756285dc\", \"sbox_unittests\": \"cdb819388ceb08f4e441401c98816ca204167a9b\", \"sbox_validation_tests\": \"1c33e4b9e8aad7866111db259bf54c3a86256e77\", \"service_manager_unittests\": \"711252615df1a0694f21d192da598c7d4c1e8554\", \"services_unittests\": \"f8745595a06d4ae0c660fae68cdc8c73fec81ee3\", \"setup_unittests\": \"f60a4d95ff3f9904f4d4ba89d7ca62177ff9d9ff\", \"shell_dialogs_unittests\": \"e4f8cf3fa49505e41524c7d6ce2a91687e2b5468\", \"skia_unittests\": \"1035f811520c2d722c1fd671311ea6edffa244b8\", \"snapshot_unittests\": \"440bf4662ea2f79081ef8d6e10f038c9e678b851\", \"sql_unittests\": \"6dc93b26201fd4dc78f4db2274331a1b2a559899\", \"storage_unittests\": \"116beda4a9c2ebe86a643aefcdf44b92c136eb17\", \"sync_integration_tests\": \"fa4c08763dcfad1988c44ecc18a98f1969376319\", \"traffic_annotation_auditor_unittests\": \"4cd1c88a93eea942d12edc43959a4130ebef5d86\", \"ui_base_unittests\": \"cd923982164fa56cdf65b2699045ad2004d2aa16\", \"ui_touch_selection_unittests\": \"a8273249f03095c51afe9b3cf5098053c000abf1\", \"unit_tests\": \"412496719cef6ab1527aa067dbaa09eba5c2e561\", \"updater_tests\": \"855771cf00e4baefff3fc44e6fc5595c15ff039c\", \"url_unittests\": \"719f1474188acdbf90211d77ff285b64188f6a7f\", \"views_unittests\": \"950c963bb9f47cccb50f24dd759eb7f39cafdf73\", \"viz_unittests\": \"0dcb29c2fd721443ee9a300dc48955ffe0656c2d\", \"vr_common_unittests\": \"b470d8e97bedf0d5db3903c3efda4cff6b6d21e6\", \"vr_pixeltests\": \"e81ed189dbc6375a8045086435b3dce36c112df0\", \"weblayer_browsertests\": \"70e9229eb28c0915c12fed295d918d3007652662\", \"weblayer_unittests\": \"69cff9d87c1199d6e7fac1797ef0f2edaa5e4f65\", \"wm_unittests\": \"11e4985335e4a42459d40ed47a347fc5f96e8180\", \"wtf_unittests\": \"3533a49edae3766d767264ec5297ac9996f7419a\", \"zlib_unittests\": \"a890387f5c9a88dc3667795f0feb5e093dc32e52\", \"zucchini_unittests\": \"bb881c14deea9e67d74c03ecccda6383108c6dfa\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-3-xpf4\"], \"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\": [\"5709-d3c4474\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**unit_tests** failed because of:\\n\\n- ContextualNotificationPermissionUiSelectorTest.PrefAndSafeBrowsingCombinations\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1614154006515055",
"status": "COMPLETED",
"status_changed_ts": "1614154007382553",
"tags": [
"build_address:luci.chromium.ci/win-asan/17504",
"builder:win-asan",
"buildset:commit/git/b90646417bd3e0106689c8eccc8d7ad9a91f1348",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b90646417bd3e0106689c8eccc8d7ad9a91f1348",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019860800027889232",
"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/8854430737607773456/+/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:51ebe2889f6e0c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1614160286634360",
"url": "https://ci.chromium.org/b/8854430737607773456",
"utcnow_ts": "1614160287057221"
}
}
[09:51:27.065] Invocation finished in 1h47m27.404096888s with status FAILED