[03:37:40.288] New invocation is queued and will start shortly
[03:37:41.391] Starting the invocation (attempt 1)
[03:37:41.431] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:37:41.431] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:37:41.431] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9018428004168166432",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"529a8811423fa9cc0165289f71a017a772592ccc\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9018428004168166432",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/529a8811423fa9cc0165289f71a017a772592ccc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/529a8811423fa9cc0165289f71a017a772592ccc",
"gitiles_ref:refs/heads/master"
]
}
[03:37:42.078] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1615520261537014",
"id": "8852997942197134816",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"529a8811423fa9cc0165289f71a017a772592ccc\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615520261963216",
"tags": [
"build_address:luci.chromium.ci/win-asan/17765",
"builder:win-asan",
"buildset:commit/git/529a8811423fa9cc0165289f71a017a772592ccc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/529a8811423fa9cc0165289f71a017a772592ccc",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018428004168166432",
"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/8852997942197134816/+/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": "1615520261963386",
"url": "https://ci.chromium.org/b/8852997942197134816",
"utcnow_ts": "1615520262072440"
}
}
[03:37:42.078] Task URL: https://ci.chromium.org/b/8852997942197134816
[03:37:42.078] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:2:0) after 1m0s
[03:38:42.097] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:2:0)
[03:38:42.097] Timer tick, asking Buildbucket for the build status
[03:38:42.181] Build 8852997942197134816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:38:42.181] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:3:0) after 1m0s
[03:39:42.241] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:3:0)
[03:39:42.241] Timer tick, asking Buildbucket for the build status
[03:39:42.331] Build 8852997942197134816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:39:42.332] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:4:0) after 1m0s
[03:40:42.441] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:4:0)
[03:40:42.441] Timer tick, asking Buildbucket for the build status
[03:40:42.698] Build 8852997942197134816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:40:42.698] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:5:0) after 1m0s
[03:41:42.690] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:5:0)
[03:41:42.690] Timer tick, asking Buildbucket for the build status
[03:41:42.841] Build 8852997942197134816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:41:42.841] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:6:0) after 1m0s
[03:42:42.879] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:6:0)
[03:42:42.879] Timer tick, asking Buildbucket for the build status
[03:42:42.994] Build 8852997942197134816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:42:42.994] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:7:0) after 1m0s
[03:43:42.997] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:7:0)
[03:43:42.997] Timer tick, asking Buildbucket for the build status
[03:43:43.109] Build 8852997942197134816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:43:43.109] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:8:0) after 1m0s
[03:44:43.150] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:8:0)
[03:44:43.150] Timer tick, asking Buildbucket for the build status
[03:44:43.255] Build 8852997942197134816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:44:43.255] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:9:0) after 1m0s
[03:45:04.337] Received PubSub notification, asking Buildbucket for the build status
[03:45:04.619] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:43.291] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:9:0)
[03:45:43.291] Timer tick, asking Buildbucket for the build status
[03:45:43.509] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:43.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:11:0) after 1m0s
[03:46:43.557] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:11:0)
[03:46:43.557] Timer tick, asking Buildbucket for the build status
[03:46:43.623] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:43.623] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:12:0) after 1m0s
[03:47:43.667] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:12:0)
[03:47:43.667] Timer tick, asking Buildbucket for the build status
[03:47:43.748] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:43.748] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:13:0) after 1m0s
[03:48:43.765] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:13:0)
[03:48:43.765] Timer tick, asking Buildbucket for the build status
[03:48:43.831] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:43.831] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:14:0) after 1m0s
[03:49:43.854] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:14:0)
[03:49:43.854] Timer tick, asking Buildbucket for the build status
[03:49:43.925] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:43.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:15:0) after 1m0s
[03:50:43.985] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:15:0)
[03:50:43.985] Timer tick, asking Buildbucket for the build status
[03:50:44.050] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:44.050] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:16:0) after 1m0s
[03:51:45.915] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:16:0)
[03:51:45.915] Timer tick, asking Buildbucket for the build status
[03:51:45.985] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:45.985] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:17:0) after 1m0s
[03:52:46.114] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:17:0)
[03:52:46.114] Timer tick, asking Buildbucket for the build status
[03:52:46.427] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:46.427] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:18:0) after 1m0s
[03:53:46.484] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:18:0)
[03:53:46.484] Timer tick, asking Buildbucket for the build status
[03:53:46.554] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:46.554] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:19:0) after 1m0s
[03:54:46.706] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:19:0)
[03:54:46.706] Timer tick, asking Buildbucket for the build status
[03:54:46.789] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:46.790] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:20:0) after 1m0s
[03:55:46.883] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:20:0)
[03:55:46.883] Timer tick, asking Buildbucket for the build status
[03:55:46.944] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:46.944] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:21:0) after 1m0s
[03:56:47.092] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:21:0)
[03:56:47.092] Timer tick, asking Buildbucket for the build status
[03:56:47.249] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:47.249] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:22:0) after 1m0s
[03:57:47.476] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:22:0)
[03:57:47.476] Timer tick, asking Buildbucket for the build status
[03:57:47.555] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:47.555] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:23:0) after 1m0s
[03:58:47.775] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:23:0)
[03:58:47.775] Timer tick, asking Buildbucket for the build status
[03:58:48.004] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:48.004] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:24:0) after 1m0s
[03:59:48.012] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:24:0)
[03:59:48.012] Timer tick, asking Buildbucket for the build status
[03:59:48.231] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:48.231] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:25:0) after 1m0s
[04:00:48.252] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:25:0)
[04:00:48.252] Timer tick, asking Buildbucket for the build status
[04:00:48.333] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:48.333] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:26:0) after 1m0s
[04:01:48.390] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:26:0)
[04:01:48.390] Timer tick, asking Buildbucket for the build status
[04:01:48.668] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:48.668] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:27:0) after 1m0s
[04:02:48.826] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:27:0)
[04:02:48.826] Timer tick, asking Buildbucket for the build status
[04:02:49.041] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:49.041] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:28:0) after 1m0s
[04:03:49.158] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:28:0)
[04:03:49.158] Timer tick, asking Buildbucket for the build status
[04:03:49.226] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:49.226] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:29:0) after 1m0s
[04:04:49.253] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:29:0)
[04:04:49.253] Timer tick, asking Buildbucket for the build status
[04:04:49.458] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:49.458] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:30:0) after 1m0s
[04:05:49.547] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:30:0)
[04:05:49.547] Timer tick, asking Buildbucket for the build status
[04:05:49.633] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:49.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:31:0) after 1m0s
[04:06:50.399] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:31:0)
[04:06:50.399] Timer tick, asking Buildbucket for the build status
[04:06:50.480] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:50.480] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:32:0) after 1m0s
[04:07:50.705] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:32:0)
[04:07:50.705] Timer tick, asking Buildbucket for the build status
[04:07:51.053] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:51.053] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:33:0) after 1m0s
[04:08:51.205] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:33:0)
[04:08:51.205] Timer tick, asking Buildbucket for the build status
[04:08:51.598] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:51.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:34:0) after 1m0s
[04:09:51.691] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:34:0)
[04:09:51.691] Timer tick, asking Buildbucket for the build status
[04:09:51.850] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:51.850] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:35:0) after 1m0s
[04:10:52.016] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:35:0)
[04:10:52.016] Timer tick, asking Buildbucket for the build status
[04:10:52.094] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:52.094] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:36:0) after 1m0s
[04:11:52.234] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:36:0)
[04:11:52.234] Timer tick, asking Buildbucket for the build status
[04:11:52.457] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:52.457] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:37:0) after 1m0s
[04:12:52.595] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:37:0)
[04:12:52.595] Timer tick, asking Buildbucket for the build status
[04:12:52.863] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:52.863] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:38:0) after 1m0s
[04:13:52.972] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:38:0)
[04:13:52.972] Timer tick, asking Buildbucket for the build status
[04:13:53.042] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:53.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:39:0) after 1m0s
[04:14:53.115] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:39:0)
[04:14:53.115] Timer tick, asking Buildbucket for the build status
[04:14:53.174] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:53.174] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:40:0) after 1m0s
[04:15:53.223] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:40:0)
[04:15:53.223] Timer tick, asking Buildbucket for the build status
[04:15:53.407] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:53.407] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:41:0) after 1m0s
[04:16:53.439] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:41:0)
[04:16:53.439] Timer tick, asking Buildbucket for the build status
[04:16:53.502] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:53.502] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:42:0) after 1m0s
[04:17:53.479] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:42:0)
[04:17:53.479] Timer tick, asking Buildbucket for the build status
[04:17:53.593] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:53.593] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:43:0) after 1m0s
[04:18:53.660] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:43:0)
[04:18:53.660] Timer tick, asking Buildbucket for the build status
[04:18:53.999] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:53.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:44:0) after 1m0s
[04:19:54.066] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:44:0)
[04:19:54.066] Timer tick, asking Buildbucket for the build status
[04:19:54.210] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:54.210] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:45:0) after 1m0s
[04:20:54.346] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:45:0)
[04:20:54.346] Timer tick, asking Buildbucket for the build status
[04:20:54.410] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:54.410] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:46:0) after 1m0s
[04:21:54.412] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:46:0)
[04:21:54.412] Timer tick, asking Buildbucket for the build status
[04:21:54.495] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:54.495] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:47:0) after 1m0s
[04:22:54.502] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:47:0)
[04:22:54.502] Timer tick, asking Buildbucket for the build status
[04:22:54.625] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:54.625] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:48:0) after 1m0s
[04:23:54.678] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:48:0)
[04:23:54.678] Timer tick, asking Buildbucket for the build status
[04:23:54.740] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:54.740] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:49:0) after 1m0s
[04:24:54.843] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:49:0)
[04:24:54.843] Timer tick, asking Buildbucket for the build status
[04:24:54.925] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:54.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:50:0) after 1m0s
[04:25:54.958] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:50:0)
[04:25:54.958] Timer tick, asking Buildbucket for the build status
[04:25:55.025] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:55.025] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:51:0) after 1m0s
[04:26:55.049] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:51:0)
[04:26:55.049] Timer tick, asking Buildbucket for the build status
[04:26:55.268] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:55.268] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:52:0) after 1m0s
[04:27:55.286] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:52:0)
[04:27:55.286] Timer tick, asking Buildbucket for the build status
[04:27:55.456] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:55.456] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:53:0) after 1m0s
[04:28:55.428] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:53:0)
[04:28:55.428] Timer tick, asking Buildbucket for the build status
[04:28:57.089] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:57.089] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:54:0) after 1m0s
[04:29:57.188] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:54:0)
[04:29:57.188] Timer tick, asking Buildbucket for the build status
[04:29:57.248] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:57.248] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:55:0) after 1m0s
[04:30:57.297] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:55:0)
[04:30:57.297] Timer tick, asking Buildbucket for the build status
[04:30:57.516] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:57.516] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:56:0) after 1m0s
[04:31:57.513] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:56:0)
[04:31:57.513] Timer tick, asking Buildbucket for the build status
[04:31:57.580] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:57.580] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:57:0) after 1m0s
[04:32:57.660] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:57:0)
[04:32:57.660] Timer tick, asking Buildbucket for the build status
[04:32:57.790] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:57.790] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:58:0) after 1m0s
[04:33:57.838] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:58:0)
[04:33:57.838] Timer tick, asking Buildbucket for the build status
[04:33:58.107] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:58.107] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:59:0) after 1m0s
[04:34:58.155] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:59:0)
[04:34:58.155] Timer tick, asking Buildbucket for the build status
[04:34:58.218] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:58.218] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:60:0) after 1m0s
[04:35:58.325] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:60:0)
[04:35:58.325] Timer tick, asking Buildbucket for the build status
[04:35:58.487] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:58.487] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:61:0) after 1m0s
[04:36:58.501] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:61:0)
[04:36:58.501] Timer tick, asking Buildbucket for the build status
[04:36:59.924] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:59.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:62:0) after 1m0s
[04:37:59.946] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:62:0)
[04:37:59.946] Timer tick, asking Buildbucket for the build status
[04:38:00.174] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:00.174] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:63:0) after 1m0s
[04:39:00.168] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:63:0)
[04:39:00.168] Timer tick, asking Buildbucket for the build status
[04:39:00.241] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:00.241] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:64:0) after 1m0s
[04:40:00.302] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:64:0)
[04:40:00.302] Timer tick, asking Buildbucket for the build status
[04:40:00.634] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:00.634] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:65:0) after 1m0s
[04:41:00.667] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:65:0)
[04:41:00.667] Timer tick, asking Buildbucket for the build status
[04:41:00.923] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:00.923] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:66:0) after 1m0s
[04:42:01.059] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:66:0)
[04:42:01.059] Timer tick, asking Buildbucket for the build status
[04:42:01.366] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:01.366] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:67:0) after 1m0s
[04:43:01.372] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:67:0)
[04:43:01.372] Timer tick, asking Buildbucket for the build status
[04:43:01.670] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:01.670] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:68:0) after 1m0s
[04:44:01.783] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:68:0)
[04:44:01.783] Timer tick, asking Buildbucket for the build status
[04:44:02.217] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:02.217] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:69:0) after 1m0s
[04:45:02.238] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:69:0)
[04:45:02.238] Timer tick, asking Buildbucket for the build status
[04:45:02.346] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:02.346] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:70:0) after 1m0s
[04:46:02.402] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:70:0)
[04:46:02.402] Timer tick, asking Buildbucket for the build status
[04:46:02.508] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:02.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:71:0) after 1m0s
[04:47:02.561] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:71:0)
[04:47:02.561] Timer tick, asking Buildbucket for the build status
[04:47:02.936] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:02.936] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:72:0) after 1m0s
[04:48:02.969] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:72:0)
[04:48:02.969] Timer tick, asking Buildbucket for the build status
[04:48:03.075] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:03.075] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:73:0) after 1m0s
[04:49:03.121] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:73:0)
[04:49:03.121] Timer tick, asking Buildbucket for the build status
[04:49:03.563] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:03.563] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:74:0) after 1m0s
[04:50:03.581] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:74:0)
[04:50:03.581] Timer tick, asking Buildbucket for the build status
[04:50:03.879] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:03.879] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:75:0) after 1m0s
[04:51:03.979] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:75:0)
[04:51:03.979] Timer tick, asking Buildbucket for the build status
[04:51:04.075] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:04.075] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:76:0) after 1m0s
[04:52:04.158] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:76:0)
[04:52:04.158] Timer tick, asking Buildbucket for the build status
[04:52:04.615] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:04.615] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:77:0) after 1m0s
[04:53:04.637] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:77:0)
[04:53:04.637] Timer tick, asking Buildbucket for the build status
[04:53:04.744] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:04.744] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:78:0) after 1m0s
[04:54:04.903] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:78:0)
[04:54:04.903] Timer tick, asking Buildbucket for the build status
[04:54:05.018] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:05.018] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:79:0) after 1m0s
[04:55:05.040] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:79:0)
[04:55:05.040] Timer tick, asking Buildbucket for the build status
[04:55:05.524] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:05.524] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:80:0) after 1m0s
[04:56:05.726] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:80:0)
[04:56:05.726] Timer tick, asking Buildbucket for the build status
[04:56:05.961] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:05.961] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:81:0) after 1m0s
[04:57:06.026] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:81:0)
[04:57:06.026] Timer tick, asking Buildbucket for the build status
[04:57:06.103] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:06.103] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:82:0) after 1m0s
[04:58:06.130] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:82:0)
[04:58:06.130] Timer tick, asking Buildbucket for the build status
[04:58:06.219] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:06.220] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:83:0) after 1m0s
[04:59:06.270] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:83:0)
[04:59:06.270] Timer tick, asking Buildbucket for the build status
[04:59:06.346] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:06.346] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:84:0) after 1m0s
[05:00:06.358] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:84:0)
[05:00:06.358] Timer tick, asking Buildbucket for the build status
[05:00:06.573] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:06.573] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:85:0) after 1m0s
[05:01:07.211] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:85:0)
[05:01:07.211] Timer tick, asking Buildbucket for the build status
[05:01:07.285] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:07.286] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:86:0) after 1m0s
[05:02:07.381] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:86:0)
[05:02:07.381] Timer tick, asking Buildbucket for the build status
[05:02:07.449] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:07.449] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:87:0) after 1m0s
[05:03:07.504] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:87:0)
[05:03:07.504] Timer tick, asking Buildbucket for the build status
[05:03:07.895] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:07.895] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:88:0) after 1m0s
[05:04:07.922] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:88:0)
[05:04:07.922] Timer tick, asking Buildbucket for the build status
[05:04:08.220] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:08.220] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:89:0) after 1m0s
[05:05:08.257] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:89:0)
[05:05:08.257] Timer tick, asking Buildbucket for the build status
[05:05:08.339] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:08.339] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:90:0) after 1m0s
[05:06:08.432] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:90:0)
[05:06:08.432] Timer tick, asking Buildbucket for the build status
[05:06:08.569] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:08.569] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:91:0) after 1m0s
[05:07:08.570] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:91:0)
[05:07:08.570] Timer tick, asking Buildbucket for the build status
[05:07:08.645] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:08.645] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:92:0) after 1m0s
[05:08:08.670] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:92:0)
[05:08:08.670] Timer tick, asking Buildbucket for the build status
[05:08:08.772] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:08.772] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:93:0) after 1m0s
[05:09:09.048] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:93:0)
[05:09:09.048] Timer tick, asking Buildbucket for the build status
[05:09:09.131] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:09.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:94:0) after 1m0s
[05:10:09.158] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:94:0)
[05:10:09.158] Timer tick, asking Buildbucket for the build status
[05:10:09.258] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:09.258] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:95:0) after 1m0s
[05:11:09.364] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:95:0)
[05:11:09.364] Timer tick, asking Buildbucket for the build status
[05:11:09.435] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:09.435] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:96:0) after 1m0s
[05:12:09.477] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:96:0)
[05:12:09.477] Timer tick, asking Buildbucket for the build status
[05:12:09.582] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:09.582] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:97:0) after 1m0s
[05:13:09.621] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:97:0)
[05:13:09.621] Timer tick, asking Buildbucket for the build status
[05:13:09.716] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:09.716] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:98:0) after 1m0s
[05:14:09.749] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:98:0)
[05:14:09.749] Timer tick, asking Buildbucket for the build status
[05:14:09.824] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:09.824] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:99:0) after 1m0s
[05:15:09.845] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:99:0)
[05:15:09.845] Timer tick, asking Buildbucket for the build status
[05:15:09.905] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:09.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:100:0) after 1m0s
[05:16:10.115] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:100:0)
[05:16:10.115] Timer tick, asking Buildbucket for the build status
[05:16:10.335] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:10.335] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:101:0) after 1m0s
[05:17:10.390] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:101:0)
[05:17:10.390] Timer tick, asking Buildbucket for the build status
[05:17:10.473] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:10.473] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:102:0) after 1m0s
[05:18:10.532] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:102:0)
[05:18:10.532] Timer tick, asking Buildbucket for the build status
[05:18:10.760] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:10.760] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:103:0) after 1m0s
[05:19:10.822] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:103:0)
[05:19:10.822] Timer tick, asking Buildbucket for the build status
[05:19:10.956] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:10.956] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:104:0) after 1m0s
[05:20:11.077] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:104:0)
[05:20:11.077] Timer tick, asking Buildbucket for the build status
[05:20:11.164] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:11.164] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:105:0) after 1m0s
[05:21:11.188] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:105:0)
[05:21:11.188] Timer tick, asking Buildbucket for the build status
[05:21:11.448] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:11.448] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:106:0) after 1m0s
[05:22:11.581] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:106:0)
[05:22:11.581] Timer tick, asking Buildbucket for the build status
[05:22:11.664] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:11.664] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:107:0) after 1m0s
[05:23:11.742] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:107:0)
[05:23:11.742] Timer tick, asking Buildbucket for the build status
[05:23:11.846] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:11.846] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:108:0) after 1m0s
[05:24:11.895] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:108:0)
[05:24:11.895] Timer tick, asking Buildbucket for the build status
[05:24:11.992] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:11.992] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:109:0) after 1m0s
[05:25:12.078] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:109:0)
[05:25:12.078] Timer tick, asking Buildbucket for the build status
[05:25:12.165] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:12.165] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:110:0) after 1m0s
[05:26:12.213] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:110:0)
[05:26:12.213] Timer tick, asking Buildbucket for the build status
[05:26:12.280] Build 8852997942197134816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:12.280] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018428004168166432:111:0) after 1m0s
[05:26:13.771] Received PubSub notification, asking Buildbucket for the build status
[05:26:13.858] Build 8852997942197134816: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:26:13.858] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1615526772826277",
"created_by": "project:chromium",
"created_ts": "1615520261537014",
"id": "8852997942197134816",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"529a8811423fa9cc0165289f71a017a772592ccc\"}}",
"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\": \"529a8811423fa9cc0165289f71a017a772592ccc\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-32-10-bsc3\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17765, \"clang_revision\": \"llvmorg-13-init-3462-gfe5c2c3c-2\", \"got_angle_revision\": \"cb5441ed17ae58ccec9562a1ffe4eed2351c5c60\", \"got_dawn_revision\": \"0702b704697ca0c9d4b41faeb1d3f80e10f07278\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"529a8811423fa9cc0165289f71a017a772592ccc\", \"got_revision_cp\": \"refs/heads/master@{#862283}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"3c4fd15fb075ab3a8eb5809f9a42b5faa9ac63ae\", \"got_v8_revision\": \"9d12b666c3e113f5cd61e2b2eb384aae242ac840\", \"got_v8_revision_cp\": \"refs/heads/9.1.105@{#1}\", \"got_webrtc_revision\": \"14a626af56675c9d95f9fb207d53366a53a95fbd\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33409}\", \"is_cached\": true, \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.win:win-asan\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"529a8811423fa9cc0165289f71a017a772592ccc\", \"swarm_hashes_refs/heads/master(at){#862283}_without_patch\": {\"absl_hardening_tests\": \"edf477556a2e14b23d410133b1f10bacc70350d7\", \"accessibility_unittests\": \"f9a3458db82ba3c869f8eb5363bde0a26922382e\", \"angle_unittests\": \"9eeeb03b0107d641754cdb494c8ea8e7a0c5a1f9\", \"app_shell_unittests\": \"704cee6b659ff5f429f38ba68ebd76d2a25509e3\", \"aura_unittests\": \"d9fe8605070f3576abbd8ef5039c373ae88e6bae\", \"base_unittests\": \"cbc02c8b50151b38f382260c154d4ff7612d9130\", \"base_util_unittests\": \"909e4d06e9e52b530e76a2bd764e727976e3fb46\", \"blink_common_unittests\": \"f20c2a66b2eb1db1fdc5e09e4a6fee8238e7e54c\", \"blink_fuzzer_unittests\": \"2d008ec155eeef72b78dc7cc62c1f0faef77a19d\", \"blink_heap_unittests\": \"6e5a3fa48188aeb8b5177888626bae6701c85677\", \"blink_platform_unittests\": \"1506013f1b2a4f2c841fe44104006ae328db5ed5\", \"blink_unittests\": \"ca4af6bd16da9ea89bb3b20229ebad94ef2a8bde\", \"boringssl_crypto_tests\": \"908c0ea56a8013697e6eb1ee185d431e58ab66ca\", \"boringssl_ssl_tests\": \"c5f86ed5f97ed2a63fd46ad26b738683005adc85\", \"browser_switcher_bho_unittests\": \"67cd5403b9c8dc9b2b1546fadccd7e55d21b0155\", \"browser_tests\": \"51448ac448d829653fb6c9a1be81cc8e4904b329\", \"capture_unittests\": \"d227317872b26e884cadc355b44fc4c88c7739e1\", \"cast_unittests\": \"00f26b54d005bad1a546ba6bc42d3e13a268b3ef\", \"cc_unittests\": \"9b82f8587ac311c8b7454d86384fff1365c54642\", \"chrome_app_unittests\": \"02a7d8a8066e13356be7ef6805738c19f38e5486\", \"chrome_cleaner_unittests\": \"4dc3adb43ddff2ec8e1797fbb83f0baadb258987\", \"chrome_elf_unittests\": \"57e570349bf6120966954f393de8aff6d0e4988f\", \"chromedriver_unittests\": \"74c2931206afdec4daa31a2b5bf17f2e3f860fd1\", \"color_unittests\": \"b99392af1b21ebcf0d968977e81d976512f75f06\", \"components_browsertests\": \"7c1a8c366627f8163b3ddad2ef618ef42d410aa3\", \"components_unittests\": \"ef375ad479e88a4684fe1b390a6007a507ccf501\", \"compositor_unittests\": \"9cc97f324342a5b5c4d690bf10b3de8a714ca031\", \"content_browsertests\": \"72895ec86b15a87071e60c0d7b95750a561a2dc6\", \"content_unittests\": \"d7a71e7ad44d6e823f1c9f218410a1bdd0418895\", \"courgette_unittests\": \"3cd2e0e7343f3509d023f0e6d05a23b13651b21e\", \"crashpad_tests\": \"fc146a6654ca64a1f66ec364134a1b7c4781c76e\", \"cronet_tests\": \"d1b5b5ef5cdd0d7484dfde66cb32e8aeef503fd3\", \"cronet_unittests\": \"9580e1018e812285e5c1211ac2a1b0a65b5acf0e\", \"crypto_unittests\": \"c55a08f775167f3a7d63698b6fa88e3396d932e2\", \"delayloads_unittests\": \"a4ff7bf33a008f97fd29aa9e6aa853d2975f072f\", \"device_unittests\": \"ab8103737cceec9c995c9c5b57cab5bdc1c47526\", \"display_unittests\": \"9326479f82e77862f18a348277467f629fbaa3de\", \"elevation_service_unittests\": \"49c7766f7fe5213013453bdc6cd50dc33d166797\", \"events_unittests\": \"9ecf0e15e595472afc99c62bcc2d9ffac84382df\", \"extensions_browsertests\": \"ef8a1df764e8917cea0a9e381a56ba92491385eb\", \"extensions_unittests\": \"2df8c72019353d3bf5a714672b864487740a6232\", \"filesystem_service_unittests\": \"bcb727734b0f4c4b764f1bfbe7a88bc42495567e\", \"gcm_unit_tests\": \"8d6fa37814147f8a5f3c9735a09523e815e7d553\", \"gcp_unittests\": \"0dc217430ea3e510b623321fac88b63bcc432950\", \"gfx_unittests\": \"5ea2706be8dbd0470a1c309e5badbcb10df42321\", \"gin_unittests\": \"7fb128f7e5f2ddc3bc54ef365d55cef399f3827c\", \"google_apis_unittests\": \"100cc2730e1283c92deb9834d5c4109363263858\", \"gpu_unittests\": \"7f229b1257f56e5c51be50341c4e1619794aa2cc\", \"gwp_asan_unittests\": \"626221dc65eab352eea542cf495121aa772364e1\", \"headless_browsertests\": \"a7bc0002bb06ef6e2eb651b16ed2e6fe725c6d4e\", \"headless_unittests\": \"d591945246d2c55bee004dd046300d1d4dfa4a29\", \"install_static_unittests\": \"4d8af23a2d20bb7f5ece97a772c7efbc7c7b5861\", \"installer_util_unittests\": \"45a09b6e108e843c818ebc9774d7ba7b5bdf34d0\", \"interactive_ui_tests\": \"a43fc12a26f304f7632c42d011ec078ac5bb514c\", \"ipc_tests\": \"7f056b7e0b431e29a23aa305aa6ef6838ddda530\", \"jingle_unittests\": \"ac7a76f1346c5daceeaf4473fa875e956528cad9\", \"latency_unittests\": \"7fd47fea249c27e2f674379e22a8ba2125230ccc\", \"libjingle_xmpp_unittests\": \"21b96807e6c142d79a46645e0515e2679281a03f\", \"liburlpattern_unittests\": \"98960badd88a66b1c221ff95a84320b34c6d7639\", \"media_blink_unittests\": \"0108bedc13c26f3be42184693b7e5f01b03b7781\", \"media_unittests\": \"827263a130e31a277de0f9e667829fd0f04f6320\", \"message_center_unittests\": \"2b8f6af50aff625090013c35ad260c8cbc14e04c\", \"midi_unittests\": \"1f668a70fd407162033851b57c40346c3e7144a8\", \"mojo_core_unittests\": \"4af0f69b58fafa6fc111710c082f18a43310c85c\", \"mojo_unittests\": \"1bad8839e883b9187c5b0fdac57b76ae428b34d7\", \"nacl_loader_unittests\": \"07205ae6f0d060ee216372a1803da734af20ce15\", \"native_theme_unittests\": \"eec83936567d4317f321cac273ff28c30877e9ad\", \"net_unittests\": \"999c1924297d8922a19d96f33c12b4e9f6a73153\", \"notification_helper_unittests\": \"ce837fcc15d8d395753910e2ce3e9f48c4106e70\", \"pdf_unittests\": \"5f7cf3e8018f28fa62758a9653aefe85e81bbf76\", \"perfetto_unittests\": \"637c6747e55401ba3dc2c0c14a56156c5e4728ad\", \"ppapi_unittests\": \"bc1594683bf47d9d501bcc94ed1e982f79efedec\", \"printing_unittests\": \"6f82ad992837663a09abfa1b7062c7c2c32c20dc\", \"remoting_unittests\": \"a61bcecb00e6665a8efc61c7118ce6748a5ba528\", \"sbox_integration_tests\": \"4131d5e5ff31729aef31e300ffa3ddf14cc79512\", \"sbox_unittests\": \"5f5aa3c604de65fff72d74924cbdba81d1ac6626\", \"sbox_validation_tests\": \"6cc7da75acaf26b31c0dfb88d0ef38dbea497924\", \"service_manager_unittests\": \"1ae9f7f02f97dafe7826865013c84671aca28c2d\", \"services_unittests\": \"3f849d14e7f9a02aa8c47ae808a9d4a11c6527f8\", \"setup_unittests\": \"98c8184145552db84f04d170b528e84e19486956\", \"shell_dialogs_unittests\": \"03a1bc7ed4ac367f5b3e944776f04b171a56444f\", \"skia_unittests\": \"7cf87772ab4a297ab52068a99246761021fe49a5\", \"snapshot_unittests\": \"580411dc00a16560fc61e70dc8dd21e5de8eb05e\", \"sql_unittests\": \"5fb81ca1912a31ef918cbb291fb48bccda87c987\", \"storage_unittests\": \"b1871b17a97bb7d39ffb140ad22914a1f388e9ff\", \"sync_integration_tests\": \"1585f9d6b8bbeb863586c1d2c22f65c475aec0ed\", \"traffic_annotation_auditor_unittests\": \"a9edeecbd852ffccc1fe7eb8890f5eba8849b6e1\", \"ui_base_unittests\": \"9e6bd64759ce4c425b991701cb04b40920e28ad7\", \"ui_touch_selection_unittests\": \"1605496516a24678623e12bf2b87000efe665e4f\", \"unit_tests\": \"1e570d932e27710db4f1fd1345ec11e7ba84521c\", \"updater_tests\": \"81e31f738b38de2ee9d3b02d99a93ad5672fb9cb\", \"url_unittests\": \"7633136d6ae12d4bf800d2acb53fd80cb3b7b668\", \"views_unittests\": \"eb20b1875c4e034a47496a31407de415c92e374a\", \"viz_unittests\": \"767c1f86251a7c36c86d49760117c56bd0a8871b\", \"vr_common_unittests\": \"cbc9bea4e86355fd8ffd3f3ac182502535a67221\", \"vr_pixeltests\": \"8c1d82c48d8a709e13055d519fcc21c4e645134c\", \"weblayer_browsertests\": \"b1a86ee10bcb923995a9c4723f515b24a3ec9694\", \"weblayer_unittests\": \"cc3070484921c25905b3d64f24c770bebb5f0e3a\", \"wm_unittests\": \"70259eb45bfb4996df5b1f916ffbb78e480046dd\", \"wtf_unittests\": \"d79acfe96f35b0aa76001e0081ca9a0914765a5c\", \"zlib_unittests\": \"6b19a44b4e81cb9f9d08d9eab29358ef3ee224ca\", \"zucchini_unittests\": \"05ddf2d58a31381fd224e7ed60ae0ff1f7c69edc\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-10-bsc3\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5754-8c4f760\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1615520703573318",
"status": "COMPLETED",
"status_changed_ts": "1615526773198831",
"tags": [
"build_address:luci.chromium.ci/win-asan/17765",
"builder:win-asan",
"buildset:commit/git/529a8811423fa9cc0165289f71a017a772592ccc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/529a8811423fa9cc0165289f71a017a772592ccc",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018428004168166432",
"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/8852997942197134816/+/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:523d547039752010",
"user_agent:luci-scheduler"
],
"updated_ts": "1615526773202908",
"url": "https://ci.chromium.org/b/8852997942197134816",
"utcnow_ts": "1615526773847150"
}
}
[05:26:13.858] Invocation finished in 1h48m33.585206332s with status SUCCEEDED