[12:30:50.215] New invocation is queued and will start shortly
[12:30:51.287] Starting the invocation (attempt 1)
[12:30:51.323] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:30:51.323] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:30:51.323] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9020568787494320848",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"39dc7d44e293bdd628e02a2f7d8ccb14533c005e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9020568787494320848",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/39dc7d44e293bdd628e02a2f7d8ccb14533c005e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/39dc7d44e293bdd628e02a2f7d8ccb14533c005e",
"gitiles_ref:refs/heads/master"
]
}
[12:30:51.880] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1613478651422110",
"id": "8855138725564687600",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"39dc7d44e293bdd628e02a2f7d8ccb14533c005e\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1613478651762333",
"tags": [
"build_address:luci.chromium.ci/win-asan/17375",
"builder:win-asan",
"buildset:commit/git/39dc7d44e293bdd628e02a2f7d8ccb14533c005e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/39dc7d44e293bdd628e02a2f7d8ccb14533c005e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020568787494320848",
"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/8855138725564687600/+/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": "1613478651762436",
"url": "https://ci.chromium.org/b/8855138725564687600",
"utcnow_ts": "1613478651872018"
}
}
[12:30:51.880] Task URL: https://ci.chromium.org/b/8855138725564687600
[12:30:51.880] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:2:0) after 1m0s
[12:31:51.891] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:2:0)
[12:31:51.891] Timer tick, asking Buildbucket for the build status
[12:31:51.957] Build 8855138725564687600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:31:51.957] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:3:0) after 1m0s
[12:32:37.660] Received PubSub notification, asking Buildbucket for the build status
[12:32:37.738] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:52.001] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:3:0)
[12:32:52.001] Timer tick, asking Buildbucket for the build status
[12:32:52.096] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:52.097] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:5:0) after 1m0s
[12:33:52.150] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:5:0)
[12:33:52.150] Timer tick, asking Buildbucket for the build status
[12:33:52.289] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:52.289] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:6:0) after 1m0s
[12:34:52.397] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:6:0)
[12:34:52.397] Timer tick, asking Buildbucket for the build status
[12:34:52.480] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:52.480] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:7:0) after 1m0s
[12:35:52.540] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:7:0)
[12:35:52.540] Timer tick, asking Buildbucket for the build status
[12:35:52.694] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:52.694] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:8:0) after 1m0s
[12:36:52.704] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:8:0)
[12:36:52.704] Timer tick, asking Buildbucket for the build status
[12:36:52.996] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:52.996] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:9:0) after 1m0s
[12:37:53.018] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:9:0)
[12:37:53.018] Timer tick, asking Buildbucket for the build status
[12:37:53.309] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:53.309] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:10:0) after 1m0s
[12:38:53.362] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:10:0)
[12:38:53.362] Timer tick, asking Buildbucket for the build status
[12:38:53.506] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:53.506] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:11:0) after 1m0s
[12:39:53.524] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:11:0)
[12:39:53.524] Timer tick, asking Buildbucket for the build status
[12:39:53.595] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:53.595] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:12:0) after 1m0s
[12:40:53.723] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:12:0)
[12:40:53.723] Timer tick, asking Buildbucket for the build status
[12:40:53.813] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:53.813] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:13:0) after 1m0s
[12:41:53.914] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:13:0)
[12:41:53.914] Timer tick, asking Buildbucket for the build status
[12:41:54.021] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:54.021] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:14:0) after 1m0s
[12:42:54.647] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:14:0)
[12:42:54.647] Timer tick, asking Buildbucket for the build status
[12:42:54.764] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:54.764] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:15:0) after 1m0s
[12:43:54.933] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:15:0)
[12:43:54.933] Timer tick, asking Buildbucket for the build status
[12:43:55.049] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:55.049] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:16:0) after 1m0s
[12:44:55.945] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:16:0)
[12:44:55.945] Timer tick, asking Buildbucket for the build status
[12:44:56.037] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:56.037] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:17:0) after 1m0s
[12:45:56.225] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:17:0)
[12:45:56.225] Timer tick, asking Buildbucket for the build status
[12:45:56.305] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:56.305] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:18:0) after 1m0s
[12:46:56.469] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:18:0)
[12:46:56.469] Timer tick, asking Buildbucket for the build status
[12:46:56.549] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:56.549] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:19:0) after 1m0s
[12:47:56.591] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:19:0)
[12:47:56.591] Timer tick, asking Buildbucket for the build status
[12:47:56.683] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:56.683] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:20:0) after 1m0s
[12:48:57.145] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:20:0)
[12:48:57.145] Timer tick, asking Buildbucket for the build status
[12:48:57.216] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:57.216] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:21:0) after 1m0s
[12:49:57.253] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:21:0)
[12:49:57.253] Timer tick, asking Buildbucket for the build status
[12:49:57.354] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:57.354] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:22:0) after 1m0s
[12:50:57.405] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:22:0)
[12:50:57.405] Timer tick, asking Buildbucket for the build status
[12:50:57.609] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:57.609] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:23:0) after 1m0s
[12:51:57.677] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:23:0)
[12:51:57.677] Timer tick, asking Buildbucket for the build status
[12:51:57.999] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:57.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:24:0) after 1m0s
[12:52:58.061] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:24:0)
[12:52:58.061] Timer tick, asking Buildbucket for the build status
[12:52:58.150] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:58.150] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:25:0) after 1m0s
[12:53:58.176] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:25:0)
[12:53:58.176] Timer tick, asking Buildbucket for the build status
[12:53:58.416] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:58.416] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:26:0) after 1m0s
[12:54:58.404] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:26:0)
[12:54:58.404] Timer tick, asking Buildbucket for the build status
[12:54:58.616] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:58.616] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:27:0) after 1m0s
[12:55:58.655] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:27:0)
[12:55:58.655] Timer tick, asking Buildbucket for the build status
[12:55:58.738] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:58.738] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:28:0) after 1m0s
[12:56:58.793] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:28:0)
[12:56:58.793] Timer tick, asking Buildbucket for the build status
[12:56:58.889] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:58.889] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:29:0) after 1m0s
[12:57:58.910] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:29:0)
[12:57:58.910] Timer tick, asking Buildbucket for the build status
[12:57:58.994] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:58.994] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:30:0) after 1m0s
[12:58:59.016] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:30:0)
[12:58:59.016] Timer tick, asking Buildbucket for the build status
[12:58:59.120] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:59.120] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:31:0) after 1m0s
[12:59:59.171] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:31:0)
[12:59:59.171] Timer tick, asking Buildbucket for the build status
[12:59:59.294] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:59.294] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:32:0) after 1m0s
[13:00:59.340] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:32:0)
[13:00:59.340] Timer tick, asking Buildbucket for the build status
[13:00:59.436] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:59.436] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:33:0) after 1m0s
[13:01:59.463] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:33:0)
[13:01:59.463] Timer tick, asking Buildbucket for the build status
[13:01:59.595] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:59.595] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:34:0) after 1m0s
[13:02:59.605] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:34:0)
[13:02:59.605] Timer tick, asking Buildbucket for the build status
[13:02:59.683] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:59.684] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:35:0) after 1m0s
[13:03:59.705] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:35:0)
[13:03:59.705] Timer tick, asking Buildbucket for the build status
[13:03:59.820] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:59.820] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:36:0) after 1m0s
[13:04:59.888] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:36:0)
[13:04:59.888] Timer tick, asking Buildbucket for the build status
[13:05:00.124] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:00.124] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:37:0) after 1m0s
[13:06:00.190] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:37:0)
[13:06:00.190] Timer tick, asking Buildbucket for the build status
[13:06:00.299] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:00.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:38:0) after 1m0s
[13:07:00.353] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:38:0)
[13:07:00.353] Timer tick, asking Buildbucket for the build status
[13:07:00.452] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:00.452] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:39:0) after 1m0s
[13:08:01.127] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:39:0)
[13:08:01.127] Timer tick, asking Buildbucket for the build status
[13:08:01.203] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:01.203] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:40:0) after 1m0s
[13:09:01.584] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:40:0)
[13:09:01.585] Timer tick, asking Buildbucket for the build status
[13:09:01.699] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:01.699] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:41:0) after 1m0s
[13:10:02.040] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:41:0)
[13:10:02.040] Timer tick, asking Buildbucket for the build status
[13:10:02.180] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:02.180] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:42:0) after 1m0s
[13:11:02.212] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:42:0)
[13:11:02.212] Timer tick, asking Buildbucket for the build status
[13:11:02.389] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:02.389] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:43:0) after 1m0s
[13:12:02.371] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:43:0)
[13:12:02.371] Timer tick, asking Buildbucket for the build status
[13:12:02.447] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:02.447] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:44:0) after 1m0s
[13:13:02.866] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:44:0)
[13:13:02.866] Timer tick, asking Buildbucket for the build status
[13:13:02.949] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:02.949] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:45:0) after 1m0s
[13:14:02.977] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:45:0)
[13:14:02.977] Timer tick, asking Buildbucket for the build status
[13:14:03.357] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:03.357] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:46:0) after 1m0s
[13:15:03.454] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:46:0)
[13:15:03.455] Timer tick, asking Buildbucket for the build status
[13:15:03.558] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:03.587] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:47:0) after 1m0s
[13:16:03.762] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:47:0)
[13:16:03.762] Timer tick, asking Buildbucket for the build status
[13:16:03.935] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:03.935] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:48:0) after 1m0s
[13:17:04.043] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:48:0)
[13:17:04.043] Timer tick, asking Buildbucket for the build status
[13:17:04.170] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:04.170] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:49:0) after 1m0s
[13:18:04.239] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:49:0)
[13:18:04.239] Timer tick, asking Buildbucket for the build status
[13:18:04.342] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:04.342] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:50:0) after 1m0s
[13:19:04.369] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:50:0)
[13:19:04.369] Timer tick, asking Buildbucket for the build status
[13:19:04.945] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:04.945] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:51:0) after 1m0s
[13:20:04.986] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:51:0)
[13:20:04.986] Timer tick, asking Buildbucket for the build status
[13:20:05.354] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:05.354] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:52:0) after 1m0s
[13:21:05.402] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:52:0)
[13:21:05.402] Timer tick, asking Buildbucket for the build status
[13:21:05.508] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:05.508] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:53:0) after 1m0s
[13:22:05.564] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:53:0)
[13:22:05.564] Timer tick, asking Buildbucket for the build status
[13:22:05.724] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:05.724] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:54:0) after 1m0s
[13:23:05.869] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:54:0)
[13:23:05.869] Timer tick, asking Buildbucket for the build status
[13:23:05.952] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:05.952] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:55:0) after 1m0s
[13:24:06.028] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:55:0)
[13:24:06.028] Timer tick, asking Buildbucket for the build status
[13:24:06.116] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:06.116] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:56:0) after 1m0s
[13:25:06.203] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:56:0)
[13:25:06.203] Timer tick, asking Buildbucket for the build status
[13:25:06.301] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:06.301] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:57:0) after 1m0s
[13:26:06.411] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:57:0)
[13:26:06.411] Timer tick, asking Buildbucket for the build status
[13:26:06.527] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:06.527] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:58:0) after 1m0s
[13:27:06.620] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:58:0)
[13:27:06.620] Timer tick, asking Buildbucket for the build status
[13:27:06.746] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:06.747] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:59:0) after 1m0s
[13:28:06.793] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:59:0)
[13:28:06.793] Timer tick, asking Buildbucket for the build status
[13:28:06.871] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:06.871] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:60:0) after 1m0s
[13:29:06.956] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:60:0)
[13:29:06.956] Timer tick, asking Buildbucket for the build status
[13:29:07.190] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:07.190] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:61:0) after 1m0s
[13:30:07.213] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:61:0)
[13:30:07.213] Timer tick, asking Buildbucket for the build status
[13:30:07.485] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:07.485] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:62:0) after 1m0s
[13:31:07.951] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:62:0)
[13:31:07.951] Timer tick, asking Buildbucket for the build status
[13:31:08.060] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:08.060] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:63:0) after 1m0s
[13:32:08.202] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:63:0)
[13:32:08.202] Timer tick, asking Buildbucket for the build status
[13:32:08.407] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:08.407] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:64:0) after 1m0s
[13:33:08.460] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:64:0)
[13:33:08.460] Timer tick, asking Buildbucket for the build status
[13:33:08.929] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:08.929] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:65:0) after 1m0s
[13:34:09.015] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:65:0)
[13:34:09.015] Timer tick, asking Buildbucket for the build status
[13:34:09.135] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:09.135] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:66:0) after 1m0s
[13:35:09.222] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:66:0)
[13:35:09.222] Timer tick, asking Buildbucket for the build status
[13:35:09.439] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:09.439] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:67:0) after 1m0s
[13:36:09.550] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:67:0)
[13:36:09.550] Timer tick, asking Buildbucket for the build status
[13:36:09.694] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:09.694] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:68:0) after 1m0s
[13:37:09.718] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:68:0)
[13:37:09.718] Timer tick, asking Buildbucket for the build status
[13:37:09.871] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:09.871] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:69:0) after 1m0s
[13:38:09.909] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:69:0)
[13:38:09.909] Timer tick, asking Buildbucket for the build status
[13:38:10.051] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:10.051] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:70:0) after 1m0s
[13:39:10.190] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:70:0)
[13:39:10.190] Timer tick, asking Buildbucket for the build status
[13:39:10.275] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:10.275] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:71:0) after 1m0s
[13:40:10.343] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:71:0)
[13:40:10.349] Timer tick, asking Buildbucket for the build status
[13:40:10.618] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:10.618] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:72:0) after 1m0s
[13:41:10.626] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:72:0)
[13:41:10.626] Timer tick, asking Buildbucket for the build status
[13:41:10.708] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:10.708] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:73:0) after 1m0s
[13:42:10.757] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:73:0)
[13:42:10.757] Timer tick, asking Buildbucket for the build status
[13:42:11.146] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:11.146] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:74:0) after 1m0s
[13:43:11.230] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:74:0)
[13:43:11.230] Timer tick, asking Buildbucket for the build status
[13:43:11.311] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:11.311] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:75:0) after 1m0s
[13:44:11.340] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:75:0)
[13:44:11.340] Timer tick, asking Buildbucket for the build status
[13:44:11.450] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:11.450] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:76:0) after 1m0s
[13:45:11.673] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:76:0)
[13:45:11.673] Timer tick, asking Buildbucket for the build status
[13:45:11.859] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:11.859] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:77:0) after 1m0s
[13:46:11.901] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:77:0)
[13:46:11.901] Timer tick, asking Buildbucket for the build status
[13:46:11.993] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:11.993] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:78:0) after 1m0s
[13:47:12.048] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:78:0)
[13:47:12.048] Timer tick, asking Buildbucket for the build status
[13:47:12.244] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:12.244] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:79:0) after 1m0s
[13:48:12.303] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:79:0)
[13:48:12.303] Timer tick, asking Buildbucket for the build status
[13:48:12.517] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:12.517] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:80:0) after 1m0s
[13:49:12.536] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:80:0)
[13:49:12.536] Timer tick, asking Buildbucket for the build status
[13:49:12.625] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:12.625] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:81:0) after 1m0s
[13:50:12.685] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:81:0)
[13:50:12.685] Timer tick, asking Buildbucket for the build status
[13:50:12.876] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:12.876] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:82:0) after 1m0s
[13:51:12.901] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:82:0)
[13:51:12.901] Timer tick, asking Buildbucket for the build status
[13:51:12.993] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:12.993] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:83:0) after 1m0s
[13:52:13.076] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:83:0)
[13:52:13.076] Timer tick, asking Buildbucket for the build status
[13:52:13.168] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:13.168] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:84:0) after 1m0s
[13:53:13.226] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:84:0)
[13:53:13.226] Timer tick, asking Buildbucket for the build status
[13:53:13.408] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:13.408] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:85:0) after 1m0s
[13:54:13.420] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:85:0)
[13:54:13.420] Timer tick, asking Buildbucket for the build status
[13:54:13.819] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:13.819] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:86:0) after 1m0s
[13:55:13.908] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:86:0)
[13:55:13.908] Timer tick, asking Buildbucket for the build status
[13:55:14.645] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:14.645] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:87:0) after 1m0s
[13:56:14.656] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:87:0)
[13:56:14.656] Timer tick, asking Buildbucket for the build status
[13:56:14.813] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:14.813] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:88:0) after 1m0s
[13:57:14.894] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:88:0)
[13:57:14.894] Timer tick, asking Buildbucket for the build status
[13:57:15.048] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:15.048] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:89:0) after 1m0s
[13:58:15.068] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:89:0)
[13:58:15.068] Timer tick, asking Buildbucket for the build status
[13:58:15.453] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:15.453] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:90:0) after 1m0s
[13:59:15.508] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:90:0)
[13:59:15.508] Timer tick, asking Buildbucket for the build status
[13:59:15.667] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:15.667] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:91:0) after 1m0s
[14:00:15.675] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:91:0)
[14:00:15.675] Timer tick, asking Buildbucket for the build status
[14:00:15.898] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:15.898] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:92:0) after 1m0s
[14:01:15.933] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:92:0)
[14:01:15.933] Timer tick, asking Buildbucket for the build status
[14:01:16.032] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:16.032] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:93:0) after 1m0s
[14:02:16.116] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:93:0)
[14:02:16.116] Timer tick, asking Buildbucket for the build status
[14:02:16.308] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:16.308] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:94:0) after 1m0s
[14:03:16.372] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:94:0)
[14:03:16.372] Timer tick, asking Buildbucket for the build status
[14:03:17.232] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:17.232] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:95:0) after 1m0s
[14:04:17.275] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:95:0)
[14:04:17.275] Timer tick, asking Buildbucket for the build status
[14:04:17.456] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:17.456] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:96:0) after 1m0s
[14:05:17.486] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:96:0)
[14:05:17.486] Timer tick, asking Buildbucket for the build status
[14:05:17.572] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:17.572] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:97:0) after 1m0s
[14:06:17.654] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:97:0)
[14:06:17.654] Timer tick, asking Buildbucket for the build status
[14:06:17.736] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:17.736] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:98:0) after 1m0s
[14:07:17.731] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:98:0)
[14:07:17.731] Timer tick, asking Buildbucket for the build status
[14:07:17.810] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:17.810] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:99:0) after 1m0s
[14:08:17.836] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:99:0)
[14:08:17.836] Timer tick, asking Buildbucket for the build status
[14:08:18.169] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:18.169] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:100:0) after 1m0s
[14:09:18.248] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:100:0)
[14:09:18.248] Timer tick, asking Buildbucket for the build status
[14:09:18.385] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:18.385] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:101:0) after 1m0s
[14:10:18.794] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:101:0)
[14:10:18.794] Timer tick, asking Buildbucket for the build status
[14:10:18.891] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:18.891] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:102:0) after 1m0s
[14:11:18.991] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:102:0)
[14:11:18.991] Timer tick, asking Buildbucket for the build status
[14:11:19.160] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:19.160] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:103:0) after 1m0s
[14:12:19.188] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:103:0)
[14:12:19.188] Timer tick, asking Buildbucket for the build status
[14:12:19.340] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:19.340] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:104:0) after 1m0s
[14:13:19.401] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:104:0)
[14:13:19.401] Timer tick, asking Buildbucket for the build status
[14:13:19.570] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:19.570] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:105:0) after 1m0s
[14:14:19.598] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:105:0)
[14:14:19.598] Timer tick, asking Buildbucket for the build status
[14:14:19.837] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:19.838] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:106:0) after 1m0s
[14:15:20.020] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:106:0)
[14:15:20.020] Timer tick, asking Buildbucket for the build status
[14:15:20.113] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:20.113] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:107:0) after 1m0s
[14:16:20.156] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:107:0)
[14:16:20.156] Timer tick, asking Buildbucket for the build status
[14:16:20.320] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:20.320] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:108:0) after 1m0s
[14:17:20.607] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:108:0)
[14:17:20.607] Timer tick, asking Buildbucket for the build status
[14:17:21.462] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:21.462] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:109:0) after 1m0s
[14:18:21.485] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:109:0)
[14:18:21.485] Timer tick, asking Buildbucket for the build status
[14:18:21.835] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:21.835] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:110:0) after 1m0s
[14:19:21.921] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:110:0)
[14:19:21.921] Timer tick, asking Buildbucket for the build status
[14:19:22.441] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:22.441] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:111:0) after 1m0s
[14:20:22.468] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:111:0)
[14:20:22.468] Timer tick, asking Buildbucket for the build status
[14:20:22.570] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:22.570] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:112:0) after 1m0s
[14:21:22.642] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:112:0)
[14:21:22.642] Timer tick, asking Buildbucket for the build status
[14:21:23.205] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:23.205] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:113:0) after 1m0s
[14:22:23.255] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:113:0)
[14:22:23.255] Timer tick, asking Buildbucket for the build status
[14:22:23.603] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:23.603] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:114:0) after 1m0s
[14:23:23.650] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:114:0)
[14:23:23.650] Timer tick, asking Buildbucket for the build status
[14:23:23.762] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:23.763] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:115:0) after 1m0s
[14:24:23.789] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:115:0)
[14:24:23.789] Timer tick, asking Buildbucket for the build status
[14:24:24.313] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:24.313] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:116:0) after 1m0s
[14:25:24.401] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:116:0)
[14:25:24.401] Timer tick, asking Buildbucket for the build status
[14:25:24.532] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:24.532] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:117:0) after 1m0s
[14:26:24.605] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:117:0)
[14:26:24.606] Timer tick, asking Buildbucket for the build status
[14:26:24.707] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:24.707] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:118:0) after 1m0s
[14:27:24.746] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:118:0)
[14:27:24.746] Timer tick, asking Buildbucket for the build status
[14:27:24.845] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:24.845] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:119:0) after 1m0s
[14:28:24.898] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:119:0)
[14:28:24.898] Timer tick, asking Buildbucket for the build status
[14:28:25.022] Build 8855138725564687600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:25.022] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020568787494320848:120:0) after 1m0s
[14:28:59.655] Received PubSub notification, asking Buildbucket for the build status
[14:28:59.749] Build 8855138725564687600: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[14:28:59.749] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1613485738657745",
"created_by": "project:chromium",
"created_ts": "1613478651422110",
"id": "8855138725564687600",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"39dc7d44e293bdd628e02a2f7d8ccb14533c005e\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 500.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"39dc7d44e293bdd628e02a2f7d8ccb14533c005e\", \"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-0-6l07\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17375, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-1\", \"got_angle_revision\": \"a8a2a71b3ab5e535def6239997f6f24da918556b\", \"got_dawn_revision\": \"e61c9d86884145733d5763ab23c15bf09acf358c\", \"got_nacl_revision\": \"8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1\", \"got_revision\": \"39dc7d44e293bdd628e02a2f7d8ccb14533c005e\", \"got_revision_cp\": \"refs/heads/master@{#854236}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"539ef8e9426e10d7611d433ee60b94de0737344a\", \"got_v8_revision\": \"ea9ca090052383a24dd5093214a04a9451b68b92\", \"got_v8_revision_cp\": \"refs/heads/9.0.176@{#1}\", \"got_webrtc_revision\": \"8408c9938cb543a7cd5e7127927f3b4d0834ad59\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33272}\", \"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\": \"39dc7d44e293bdd628e02a2f7d8ccb14533c005e\", \"swarm_hashes_refs/heads/master(at){#854236}_without_patch\": {\"absl_hardening_tests\": \"50939bbea34dcb026d3b30cf9ace7727c9e4ec6e\", \"accessibility_unittests\": \"17b5346cac43a45ad08c003f093c173f5087e168\", \"angle_unittests\": \"ea0e8f1a821d83ea0cd52cd7ecdd967b4c8d5840\", \"app_shell_unittests\": \"0d38220d16cb7b0611f52c2ff44611713c3bf98b\", \"aura_unittests\": \"3b45a70cfc980a08b806f131442b802b45af4d8b\", \"base_unittests\": \"bc424753c674eff33ed7b34b598520b0a4733890\", \"base_util_unittests\": \"40fe01e610608a9dbc9c32648860f20c8c2df4ac\", \"blink_common_unittests\": \"94529bbdbad7835ac0bdb7e244f448bcad82cbb7\", \"blink_fuzzer_unittests\": \"64a45bd20a06745c1927ef3f05055bfbe3584b07\", \"blink_heap_unittests\": \"0399d60f5c538546570f55ec47dcc483350df0fa\", \"blink_platform_unittests\": \"7676736c7f2142e1e20d247ba2f1ea59212dfd2e\", \"blink_unittests\": \"ef06728a82450131b03f9597c7e3e751b8113ef1\", \"boringssl_crypto_tests\": \"cc338167f473f51956ab1cccec785393af39f69c\", \"boringssl_ssl_tests\": \"f68bc4ec46f629202f664704e5c5abb8f9e04483\", \"browser_switcher_bho_unittests\": \"d0db71e8e4d567bd7ba3d941743b84125e6346fe\", \"browser_tests\": \"99854c5708fcb8ca4e72b2f48511de5d102e0c53\", \"capture_unittests\": \"b956a06e32e5b844c3fa00e53e62605fdf7978c9\", \"cast_unittests\": \"028ceae85499552861d31e636e9cdb158d0d2047\", \"cc_unittests\": \"d4adc0dcac54308c5ad2f2608a875c1d394908be\", \"chrome_app_unittests\": \"9266ae32baba0bce19a7e95a7029d1e145b8ccdf\", \"chrome_cleaner_unittests\": \"cff9ef497a4edfd6bf00eed0f271f3c680ef8df5\", \"chrome_elf_unittests\": \"bc46689c5109d7ac49bfc23522f217b4f9b73bac\", \"chromedriver_unittests\": \"d44726bfd31c7c3c8200cf3a82a24e6781c62cb3\", \"components_browsertests\": \"912c36885374e7056a2e40a9e481080ac032772d\", \"components_unittests\": \"ea4a902ecbebdc1cb61f5d2388dade77ca590dd6\", \"compositor_unittests\": \"3ddba02a91ae1082eb0a9db283c9be031fad7fbf\", \"content_browsertests\": \"1dfb6bbbc3126af5a6e4e90d50ea1f3508ea47bc\", \"content_unittests\": \"8c075330739a617883660acf6ce3ee30fe6dd9e1\", \"courgette_unittests\": \"cb6d752c04c27a8f646e0302310ac93c0b80b2ca\", \"crashpad_tests\": \"6ae8ed2413c8cd7fab2ae6403da33690c7bd427e\", \"cronet_tests\": \"12110d242a9f799cc30cdfb5fbb83600199a8df0\", \"cronet_unittests\": \"34f3e09972420a0ff8948fe1d4c4409f1553ce51\", \"crypto_unittests\": \"5875b1093e7be8684946e0281a674a7854a60b8f\", \"delayloads_unittests\": \"9d5bda5b5a3f7067f8cee8daf2680209db60ca17\", \"device_unittests\": \"dd595b74eb275020f745cf2884889c7b32e5e981\", \"display_unittests\": \"5e6afd6ba5510e13516bda02e0073bf39e6d71c2\", \"elevation_service_unittests\": \"254352594203180c74068e47aa3c1c6247e20103\", \"events_unittests\": \"892f4c0ed64d73fc81b91c982140adee86eeccc1\", \"extensions_browsertests\": \"704a755cc0022094c39b9ff6c604831067162a40\", \"extensions_unittests\": \"f6b8a495c219d006bba618bd3b7336719f743f9c\", \"filesystem_service_unittests\": \"38971ba0688e64da210100f14895274109554755\", \"gcm_unit_tests\": \"c1a3b5a2a8f77c683e9159add082505763b10836\", \"gcp_unittests\": \"c18b8eb536d0a1c17c5d231a32bf4e14855f00e3\", \"gfx_unittests\": \"9dc331b2cd00045891c5654efe75270f3c24bad1\", \"gin_unittests\": \"bd8920161136fceb12392a943fcdc38d67a3e34a\", \"google_apis_unittests\": \"ee9f87584d58f7d2aac5b8bd1c2eefe3962e4c89\", \"gpu_unittests\": \"c5c25627ef53a0f5e04780cf791b479ae0b3f588\", \"gwp_asan_unittests\": \"4b866750ca5cccf730c337168879d94415633dfb\", \"headless_browsertests\": \"2c62ee76c7684bbda9cbf789f9d3be251b98aa18\", \"headless_unittests\": \"43b0680cd6e2cb65e99af764bd23e8723dc3ba6f\", \"install_static_unittests\": \"561bf8e6a2beff58b4feeb2e8341678ec5493a3c\", \"installer_util_unittests\": \"f156b9cb53e78497240a5fac64823d1c1b13bbd8\", \"interactive_ui_tests\": \"c61e7ed3438504e6ddd96a8e145fa03873ad6f6b\", \"ipc_tests\": \"d556a4cae5fe12a3db9612bb2a44784ada1655fe\", \"jingle_unittests\": \"f88ddecb4457989a80c78c3c72d610e68872f4e5\", \"latency_unittests\": \"10880bd8361f5ca528db3cfbf49bc078dab05448\", \"libjingle_xmpp_unittests\": \"3c728f38b5a5a3d93fd3daf70f8f610798e156f4\", \"liburlpattern_unittests\": \"fcc757a24d621b4225f00231c60e33085df0cd2b\", \"media_blink_unittests\": \"7bf5d7b124015c779abde00f29e71f6ddc8624fc\", \"media_unittests\": \"acb1d3d61b3de5bbdf72bb07e5b3a4084651339c\", \"message_center_unittests\": \"1ab01df2f882bddc6cc555bab8609107da89ebd9\", \"midi_unittests\": \"844be1cc0fbbe9e476a73e2460a8ba93cd0bd757\", \"mojo_core_unittests\": \"4f0b8575801f2047f3637bd9b6a1bd4a56aea5e8\", \"mojo_unittests\": \"dc9f9d8045edf592f406b02ed72cd7c7e145ba30\", \"nacl_loader_unittests\": \"0d1d789378649e7a7085806acc9e13a297dbcd0c\", \"native_theme_unittests\": \"131c960e9cdfc5e07705ac03cb926743eda3dab0\", \"net_unittests\": \"01a3e6f1e785c1e3eb797a2ecfea63ef7e0ea84e\", \"notification_helper_unittests\": \"7de2e2e76f437e33b168a11c0f63b6fdcddbe88b\", \"pdf_unittests\": \"3cd9020263fafa41e9df59ecc7ba7809de435485\", \"perfetto_unittests\": \"32b739013abafcd1866a99e1df764a2f098800e1\", \"ppapi_unittests\": \"4f94e016f3b723229ad572bbbbfdd8cea7cdf06f\", \"printing_unittests\": \"7c99e60376115946302b618482d23af4127adbdf\", \"remoting_unittests\": \"bcaeb6c16a2ea0546fc4b3398dbb0e0e6ccc2c8e\", \"sbox_integration_tests\": \"33f5dc1a462bc84958b9b9939e6fdac21b821481\", \"sbox_unittests\": \"be4ff37ae73c3231d480d76924a4a881ad405a93\", \"sbox_validation_tests\": \"dada1fb9e1fe275753b62728ba99d18109f45a40\", \"service_manager_unittests\": \"5fb50da570378f5366b6b7a029594ee59b7c5c6d\", \"services_unittests\": \"f820da5b96ba56050af3ca2ca6e112fea3668cef\", \"setup_unittests\": \"685b59661011926aa9a230bc54e313b0b95981c1\", \"shell_dialogs_unittests\": \"7c76fe2e1c5b16769342a5c85e7f41604f96db8b\", \"skia_unittests\": \"144aee4dc4d1c843ef159b6300d056bb05a2a167\", \"snapshot_unittests\": \"c26cda525341e3b63ce4f0ed60dbcd89647e2653\", \"sql_unittests\": \"caaffe64a54d9bc1c6bfc1d82b3dd56c9e8fbbe4\", \"storage_unittests\": \"37011d8c0a609dd192253cd91187f3471ef5be2b\", \"sync_integration_tests\": \"c3b65245bec2d4e4e30b870097981a1f1b5a5f3f\", \"traffic_annotation_auditor_unittests\": \"20c313bda49e2a83459425f96e0dded0070ffc13\", \"ui_base_unittests\": \"0ca96a32572e005aa8eb8a31e0703fb99c3ad37a\", \"ui_touch_selection_unittests\": \"761729df74b9a4eab9bc6e287556d34c5948183c\", \"unit_tests\": \"0fef72d19dcfc51db73a298446c89dd8013cdd93\", \"updater_tests\": \"729b45b79f76db2e05bf9a0efaa587b7ae244ed2\", \"url_unittests\": \"03fac2bc9893d643b36630ba3d09be0173ed390f\", \"views_unittests\": \"bf32f1f81354dcb6fe69998d476097c51cd63c8c\", \"viz_unittests\": \"edd9f7574241a9b1c129c23493c6c4be93139ac8\", \"vr_common_unittests\": \"1a437471317c05c22b98b67ea1eede666916f540\", \"vr_pixeltests\": \"200a923ed358f762af55218834898334a5be47aa\", \"weblayer_browsertests\": \"8d4f428527049fdfce22299ec276fffeb16ac5e4\", \"weblayer_unittests\": \"6730ccc611cd58c55b7444b856e2eb92d2fb15f8\", \"wm_unittests\": \"aec9d9cf4dbce54d58d840b772e5bf1e946f1b64\", \"wtf_unittests\": \"f2b10dc154d5bd2fd90d7371e45633d83dab708a\", \"zlib_unittests\": \"e0e202c72cf5c76b10fae1b3240f09735a8056cc\", \"zucchini_unittests\": \"92b89650f70e7a74fafbde352b3c5e2726617ed8\"}}, \"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-0-6l07\"], \"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\": [\"5700-b36f18b\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1613478756828961",
"status": "COMPLETED",
"status_changed_ts": "1613485739130024",
"tags": [
"build_address:luci.chromium.ci/win-asan/17375",
"builder:win-asan",
"buildset:commit/git/39dc7d44e293bdd628e02a2f7d8ccb14533c005e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/39dc7d44e293bdd628e02a2f7d8ccb14533c005e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020568787494320848",
"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/8855138725564687600/+/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:51c3a3f038764510",
"user_agent:luci-scheduler"
],
"updated_ts": "1613485739134875",
"url": "https://ci.chromium.org/b/8855138725564687600",
"utcnow_ts": "1613485739740427"
}
}
[14:28:59.749] Invocation finished in 1h58m9.550326851s with status SUCCEEDED