[02:22:54.753] New invocation is queued and will start shortly
[02:22:55.943] Starting the invocation (attempt 1)
[02:22:55.979] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:22:55.984] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:22:55.984] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9024321510410413328",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"95d619c574cde934211e74cd2ed46a0de988ca0c\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9024321510410413328",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/95d619c574cde934211e74cd2ed46a0de988ca0c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/95d619c574cde934211e74cd2ed46a0de988ca0c",
"gitiles_ref:refs/heads/master"
]
}
[02:22:56.752] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1609899776082180",
"id": "8858891448353804160",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"95d619c574cde934211e74cd2ed46a0de988ca0c\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1609899776564517",
"tags": [
"build_address:luci.chromium.ci/win-asan/16647",
"builder:win-asan",
"buildset:commit/git/95d619c574cde934211e74cd2ed46a0de988ca0c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/95d619c574cde934211e74cd2ed46a0de988ca0c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024321510410413328",
"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/8858891448353804160/+/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": "1609899776564671",
"url": "https://ci.chromium.org/b/8858891448353804160",
"utcnow_ts": "1609899776745561"
}
}
[02:22:56.752] Task URL: https://ci.chromium.org/b/8858891448353804160
[02:22:56.752] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:2:0) after 1m0s
[02:23:56.813] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:2:0)
[02:23:56.813] Timer tick, asking Buildbucket for the build status
[02:23:57.179] Build 8858891448353804160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:23:57.179] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:3:0) after 1m0s
[02:24:57.198] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:3:0)
[02:24:57.198] Timer tick, asking Buildbucket for the build status
[02:24:57.361] Build 8858891448353804160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:24:57.361] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:4:0) after 1m0s
[02:25:57.442] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:4:0)
[02:25:57.442] Timer tick, asking Buildbucket for the build status
[02:25:57.524] Build 8858891448353804160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:25:57.524] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:5:0) after 1m0s
[02:26:57.594] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:5:0)
[02:26:57.594] Timer tick, asking Buildbucket for the build status
[02:26:57.812] Build 8858891448353804160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:26:57.812] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:6:0) after 1m0s
[02:27:21.664] Received PubSub notification, asking Buildbucket for the build status
[02:27:21.733] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:57.927] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:6:0)
[02:27:57.927] Timer tick, asking Buildbucket for the build status
[02:27:58.224] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:58.224] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:8:0) after 1m0s
[02:28:58.297] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:8:0)
[02:28:58.297] Timer tick, asking Buildbucket for the build status
[02:28:58.684] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:58.684] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:9:0) after 1m0s
[02:29:58.787] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:9:0)
[02:29:58.787] Timer tick, asking Buildbucket for the build status
[02:29:59.064] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:59.064] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:10:0) after 1m0s
[02:30:59.183] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:10:0)
[02:30:59.183] Timer tick, asking Buildbucket for the build status
[02:30:59.323] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:59.323] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:11:0) after 1m0s
[02:31:59.555] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:11:0)
[02:31:59.555] Timer tick, asking Buildbucket for the build status
[02:31:59.709] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:59.709] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:12:0) after 1m0s
[02:32:59.760] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:12:0)
[02:32:59.760] Timer tick, asking Buildbucket for the build status
[02:32:59.881] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:59.881] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:13:0) after 1m0s
[02:33:59.971] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:13:0)
[02:33:59.972] Timer tick, asking Buildbucket for the build status
[02:34:00.195] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:00.195] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:14:0) after 1m0s
[02:35:00.287] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:14:0)
[02:35:00.287] Timer tick, asking Buildbucket for the build status
[02:35:00.449] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:00.449] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:15:0) after 1m0s
[02:36:00.930] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:15:0)
[02:36:00.930] Timer tick, asking Buildbucket for the build status
[02:36:01.226] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:01.226] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:16:0) after 1m0s
[02:37:01.657] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:16:0)
[02:37:01.657] Timer tick, asking Buildbucket for the build status
[02:37:02.150] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:02.150] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:17:0) after 1m0s
[02:38:02.169] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:17:0)
[02:38:02.169] Timer tick, asking Buildbucket for the build status
[02:38:02.259] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:02.260] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:18:0) after 1m0s
[02:39:02.396] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:18:0)
[02:39:02.396] Timer tick, asking Buildbucket for the build status
[02:39:02.594] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:02.594] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:19:0) after 1m0s
[02:40:02.688] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:19:0)
[02:40:02.688] Timer tick, asking Buildbucket for the build status
[02:40:02.804] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:02.804] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:20:0) after 1m0s
[02:41:02.861] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:20:0)
[02:41:02.861] Timer tick, asking Buildbucket for the build status
[02:41:03.190] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:03.190] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:21:0) after 1m0s
[02:42:03.283] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:21:0)
[02:42:03.283] Timer tick, asking Buildbucket for the build status
[02:42:03.736] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:03.737] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:22:0) after 1m0s
[02:43:03.802] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:22:0)
[02:43:03.802] Timer tick, asking Buildbucket for the build status
[02:43:04.077] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:04.077] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:23:0) after 1m0s
[02:44:04.176] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:23:0)
[02:44:04.176] Timer tick, asking Buildbucket for the build status
[02:44:04.613] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:04.613] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:24:0) after 1m0s
[02:45:04.645] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:24:0)
[02:45:04.645] Timer tick, asking Buildbucket for the build status
[02:45:04.803] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:04.803] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:25:0) after 1m0s
[02:46:04.880] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:25:0)
[02:46:04.880] Timer tick, asking Buildbucket for the build status
[02:46:05.305] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:05.305] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:26:0) after 1m0s
[02:47:05.330] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:26:0)
[02:47:05.330] Timer tick, asking Buildbucket for the build status
[02:47:05.586] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:05.586] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:27:0) after 1m0s
[02:48:05.701] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:27:0)
[02:48:05.701] Timer tick, asking Buildbucket for the build status
[02:48:06.114] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:06.114] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:28:0) after 1m0s
[02:49:06.110] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:28:0)
[02:49:06.110] Timer tick, asking Buildbucket for the build status
[02:49:06.196] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:06.196] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:29:0) after 1m0s
[02:50:06.282] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:29:0)
[02:50:06.282] Timer tick, asking Buildbucket for the build status
[02:50:06.534] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:06.534] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:30:0) after 1m0s
[02:51:06.597] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:30:0)
[02:51:06.597] Timer tick, asking Buildbucket for the build status
[02:51:06.888] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:06.888] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:31:0) after 1m0s
[02:52:07.031] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:31:0)
[02:52:07.031] Timer tick, asking Buildbucket for the build status
[02:52:07.219] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:07.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:32:0) after 1m0s
[02:53:07.300] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:32:0)
[02:53:07.300] Timer tick, asking Buildbucket for the build status
[02:53:07.608] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:07.608] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:33:0) after 1m0s
[02:54:07.666] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:33:0)
[02:54:07.666] Timer tick, asking Buildbucket for the build status
[02:54:07.748] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:07.748] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:34:0) after 1m0s
[02:55:07.742] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:34:0)
[02:55:07.742] Timer tick, asking Buildbucket for the build status
[02:55:07.837] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:07.837] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:35:0) after 1m0s
[02:56:07.886] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:35:0)
[02:56:07.886] Timer tick, asking Buildbucket for the build status
[02:56:07.952] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:07.952] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:36:0) after 1m0s
[02:57:07.958] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:36:0)
[02:57:07.958] Timer tick, asking Buildbucket for the build status
[02:57:08.202] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:08.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:37:0) after 1m0s
[02:58:08.218] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:37:0)
[02:58:08.218] Timer tick, asking Buildbucket for the build status
[02:58:08.360] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:08.360] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:38:0) after 1m0s
[02:59:08.400] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:38:0)
[02:59:08.400] Timer tick, asking Buildbucket for the build status
[02:59:08.502] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:08.502] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:39:0) after 1m0s
[03:00:08.567] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:39:0)
[03:00:08.567] Timer tick, asking Buildbucket for the build status
[03:00:08.701] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:08.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:40:0) after 1m0s
[03:01:08.749] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:40:0)
[03:01:08.749] Timer tick, asking Buildbucket for the build status
[03:01:08.876] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:08.876] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:41:0) after 1m0s
[03:02:08.923] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:41:0)
[03:02:08.923] Timer tick, asking Buildbucket for the build status
[03:02:09.016] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:09.016] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:42:0) after 1m0s
[03:03:09.123] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:42:0)
[03:03:09.123] Timer tick, asking Buildbucket for the build status
[03:03:09.309] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:09.309] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:43:0) after 1m0s
[03:04:09.357] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:43:0)
[03:04:09.357] Timer tick, asking Buildbucket for the build status
[03:04:09.451] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:09.451] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:44:0) after 1m0s
[03:05:09.494] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:44:0)
[03:05:09.494] Timer tick, asking Buildbucket for the build status
[03:05:09.606] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:09.606] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:45:0) after 1m0s
[03:06:09.694] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:45:0)
[03:06:09.694] Timer tick, asking Buildbucket for the build status
[03:06:09.997] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:09.997] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:46:0) after 1m0s
[03:07:10.051] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:46:0)
[03:07:10.051] Timer tick, asking Buildbucket for the build status
[03:07:10.136] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:10.136] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:47:0) after 1m0s
[03:08:10.189] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:47:0)
[03:08:10.189] Timer tick, asking Buildbucket for the build status
[03:08:10.387] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:10.387] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:48:0) after 1m0s
[03:09:10.538] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:48:0)
[03:09:10.538] Timer tick, asking Buildbucket for the build status
[03:09:10.628] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:10.628] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:49:0) after 1m0s
[03:10:10.712] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:49:0)
[03:10:10.712] Timer tick, asking Buildbucket for the build status
[03:10:10.989] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:10.989] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:50:0) after 1m0s
[03:11:11.090] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:50:0)
[03:11:11.090] Timer tick, asking Buildbucket for the build status
[03:11:11.531] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:11.531] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:51:0) after 1m0s
[03:12:11.586] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:51:0)
[03:12:11.586] Timer tick, asking Buildbucket for the build status
[03:12:11.730] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:11.730] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:52:0) after 1m0s
[03:13:11.778] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:52:0)
[03:13:11.778] Timer tick, asking Buildbucket for the build status
[03:13:11.879] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:11.879] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:53:0) after 1m0s
[03:14:11.905] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:53:0)
[03:14:11.905] Timer tick, asking Buildbucket for the build status
[03:14:11.994] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:11.994] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:54:0) after 1m0s
[03:15:12.026] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:54:0)
[03:15:12.026] Timer tick, asking Buildbucket for the build status
[03:15:12.086] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:12.087] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:55:0) after 1m0s
[03:16:12.129] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:55:0)
[03:16:12.129] Timer tick, asking Buildbucket for the build status
[03:16:12.208] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:12.208] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:56:0) after 1m0s
[03:17:12.314] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:56:0)
[03:17:12.314] Timer tick, asking Buildbucket for the build status
[03:17:12.399] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:12.399] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:57:0) after 1m0s
[03:18:12.449] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:57:0)
[03:18:12.449] Timer tick, asking Buildbucket for the build status
[03:18:12.779] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:12.779] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:58:0) after 1m0s
[03:19:12.814] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:58:0)
[03:19:12.814] Timer tick, asking Buildbucket for the build status
[03:19:12.887] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:12.887] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:59:0) after 1m0s
[03:20:12.928] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:59:0)
[03:20:12.928] Timer tick, asking Buildbucket for the build status
[03:20:12.996] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:12.996] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:60:0) after 1m0s
[03:21:13.085] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:60:0)
[03:21:13.085] Timer tick, asking Buildbucket for the build status
[03:21:13.175] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:13.175] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:61:0) after 1m0s
[03:22:13.218] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:61:0)
[03:22:13.218] Timer tick, asking Buildbucket for the build status
[03:22:13.312] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:13.312] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:62:0) after 1m0s
[03:23:13.424] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:62:0)
[03:23:13.424] Timer tick, asking Buildbucket for the build status
[03:23:13.553] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:13.553] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:63:0) after 1m0s
[03:24:13.605] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:63:0)
[03:24:13.605] Timer tick, asking Buildbucket for the build status
[03:24:13.664] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:13.664] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:64:0) after 1m0s
[03:25:13.746] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:64:0)
[03:25:13.746] Timer tick, asking Buildbucket for the build status
[03:25:13.849] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:13.849] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:65:0) after 1m0s
[03:26:13.814] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:65:0)
[03:26:13.814] Timer tick, asking Buildbucket for the build status
[03:26:13.920] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:13.920] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:66:0) after 1m0s
[03:27:13.946] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:66:0)
[03:27:13.946] Timer tick, asking Buildbucket for the build status
[03:27:14.259] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:14.259] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:67:0) after 1m0s
[03:28:14.280] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:67:0)
[03:28:14.280] Timer tick, asking Buildbucket for the build status
[03:28:14.413] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:14.413] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:68:0) after 1m0s
[03:29:14.492] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:68:0)
[03:29:14.492] Timer tick, asking Buildbucket for the build status
[03:29:14.592] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:14.592] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:69:0) after 1m0s
[03:30:14.652] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:69:0)
[03:30:14.652] Timer tick, asking Buildbucket for the build status
[03:30:15.212] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:15.212] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:70:0) after 1m0s
[03:31:15.296] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:70:0)
[03:31:15.296] Timer tick, asking Buildbucket for the build status
[03:31:15.425] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:15.425] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:71:0) after 1m0s
[03:32:15.456] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:71:0)
[03:32:15.456] Timer tick, asking Buildbucket for the build status
[03:32:15.543] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:15.543] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:72:0) after 1m0s
[03:33:15.544] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:72:0)
[03:33:15.544] Timer tick, asking Buildbucket for the build status
[03:33:19.685] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:19.685] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:73:0) after 1m0s
[03:34:19.667] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:73:0)
[03:34:19.667] Timer tick, asking Buildbucket for the build status
[03:34:19.969] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:19.969] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:74:0) after 1m0s
[03:35:20.059] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:74:0)
[03:35:20.059] Timer tick, asking Buildbucket for the build status
[03:35:20.777] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:20.777] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:75:0) after 1m0s
[03:36:20.833] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:75:0)
[03:36:20.833] Timer tick, asking Buildbucket for the build status
[03:36:21.015] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:21.015] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:76:0) after 1m0s
[03:37:21.077] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:76:0)
[03:37:21.078] Timer tick, asking Buildbucket for the build status
[03:37:21.317] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:21.317] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:77:0) after 1m0s
[03:38:21.408] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:77:0)
[03:38:21.408] Timer tick, asking Buildbucket for the build status
[03:38:21.602] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:21.602] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:78:0) after 1m0s
[03:39:21.735] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:78:0)
[03:39:21.735] Timer tick, asking Buildbucket for the build status
[03:39:21.873] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:21.873] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:79:0) after 1m0s
[03:40:21.943] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:79:0)
[03:40:21.943] Timer tick, asking Buildbucket for the build status
[03:40:22.430] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:22.430] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:80:0) after 1m0s
[03:41:22.478] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:80:0)
[03:41:22.478] Timer tick, asking Buildbucket for the build status
[03:41:23.197] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:23.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:81:0) after 1m0s
[03:42:23.299] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:81:0)
[03:42:23.299] Timer tick, asking Buildbucket for the build status
[03:42:23.804] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:23.804] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:82:0) after 1m0s
[03:43:23.809] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:82:0)
[03:43:23.809] Timer tick, asking Buildbucket for the build status
[03:43:23.976] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:23.976] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:83:0) after 1m0s
[03:44:24.106] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:83:0)
[03:44:24.106] Timer tick, asking Buildbucket for the build status
[03:44:24.428] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:24.428] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:84:0) after 1m0s
[03:45:24.513] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:84:0)
[03:45:24.513] Timer tick, asking Buildbucket for the build status
[03:45:24.660] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:24.660] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:85:0) after 1m0s
[03:46:24.774] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:85:0)
[03:46:24.774] Timer tick, asking Buildbucket for the build status
[03:46:25.654] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:25.654] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:86:0) after 1m0s
[03:47:25.858] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:86:0)
[03:47:25.858] Timer tick, asking Buildbucket for the build status
[03:47:25.958] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:25.958] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:87:0) after 1m0s
[03:48:26.073] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:87:0)
[03:48:26.073] Timer tick, asking Buildbucket for the build status
[03:48:26.228] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:26.228] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:88:0) after 1m0s
[03:49:26.328] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:88:0)
[03:49:26.328] Timer tick, asking Buildbucket for the build status
[03:49:26.481] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:26.481] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:89:0) after 1m0s
[03:50:26.493] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:89:0)
[03:50:26.493] Timer tick, asking Buildbucket for the build status
[03:50:26.694] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:26.694] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:90:0) after 1m0s
[03:51:26.831] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:90:0)
[03:51:26.831] Timer tick, asking Buildbucket for the build status
[03:51:26.918] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:26.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:91:0) after 1m0s
[03:52:27.036] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:91:0)
[03:52:27.036] Timer tick, asking Buildbucket for the build status
[03:52:27.117] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:27.117] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:92:0) after 1m0s
[03:53:27.257] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:92:0)
[03:53:27.257] Timer tick, asking Buildbucket for the build status
[03:53:27.362] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:27.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:93:0) after 1m0s
[03:54:27.371] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:93:0)
[03:54:27.371] Timer tick, asking Buildbucket for the build status
[03:54:27.449] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:27.449] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:94:0) after 1m0s
[03:55:27.512] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:94:0)
[03:55:27.512] Timer tick, asking Buildbucket for the build status
[03:55:27.631] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:27.631] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:95:0) after 1m0s
[03:56:27.653] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:95:0)
[03:56:27.653] Timer tick, asking Buildbucket for the build status
[03:56:27.755] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:27.755] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:96:0) after 1m0s
[03:57:27.793] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:96:0)
[03:57:27.793] Timer tick, asking Buildbucket for the build status
[03:57:27.852] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:27.852] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:97:0) after 1m0s
[03:58:27.892] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:97:0)
[03:58:27.892] Timer tick, asking Buildbucket for the build status
[03:58:28.005] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:28.005] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:98:0) after 1m0s
[03:59:28.028] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:98:0)
[03:59:28.028] Timer tick, asking Buildbucket for the build status
[03:59:28.163] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:28.163] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:99:0) after 1m0s
[04:00:28.253] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:99:0)
[04:00:28.253] Timer tick, asking Buildbucket for the build status
[04:00:28.355] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:28.355] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:100:0) after 1m0s
[04:01:28.489] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:100:0)
[04:01:28.489] Timer tick, asking Buildbucket for the build status
[04:01:28.657] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:28.657] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:101:0) after 1m0s
[04:02:28.699] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:101:0)
[04:02:28.699] Timer tick, asking Buildbucket for the build status
[04:02:29.352] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:29.352] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:102:0) after 1m0s
[04:03:29.403] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:102:0)
[04:03:29.403] Timer tick, asking Buildbucket for the build status
[04:03:29.609] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:29.609] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:103:0) after 1m0s
[04:04:29.637] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:103:0)
[04:04:29.637] Timer tick, asking Buildbucket for the build status
[04:04:29.712] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:29.712] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:104:0) after 1m0s
[04:05:29.717] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:104:0)
[04:05:29.717] Timer tick, asking Buildbucket for the build status
[04:05:29.878] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:29.878] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:105:0) after 1m0s
[04:06:29.992] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:105:0)
[04:06:29.992] Timer tick, asking Buildbucket for the build status
[04:06:30.109] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:30.109] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:106:0) after 1m0s
[04:07:30.181] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:106:0)
[04:07:30.181] Timer tick, asking Buildbucket for the build status
[04:07:30.341] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:30.341] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:107:0) after 1m0s
[04:08:30.447] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:107:0)
[04:08:30.447] Timer tick, asking Buildbucket for the build status
[04:08:30.563] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:30.564] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:108:0) after 1m0s
[04:09:30.643] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:108:0)
[04:09:30.643] Timer tick, asking Buildbucket for the build status
[04:09:30.908] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:30.908] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:109:0) after 1m0s
[04:10:30.945] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:109:0)
[04:10:30.945] Timer tick, asking Buildbucket for the build status
[04:10:31.034] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:31.034] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:110:0) after 1m0s
[04:11:31.074] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:110:0)
[04:11:31.074] Timer tick, asking Buildbucket for the build status
[04:11:31.265] Build 8858891448353804160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:31.265] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024321510410413328:111:0) after 1m0s
[04:12:19.523] Received PubSub notification, asking Buildbucket for the build status
[04:12:19.757] Build 8858891448353804160: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[04:12:19.757] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1609906338768104",
"created_by": "project:chromium",
"created_ts": "1609899776082180",
"failure_reason": "BUILD_FAILURE",
"id": "8858891448353804160",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"95d619c574cde934211e74cd2ed46a0de988ca0c\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**content_browsertests** failed because of:\\n\\n- All/StartupTracingTest.TestEnableTracing/7\\n\\n- All/StartupTracingTest.TestEnableTracing/6\\n\\n- All/StartupTracingTest.TestEnableTracing/8\"}, \"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\": \"95d619c574cde934211e74cd2ed46a0de988ca0c\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-us-central1-b-32-0-fy7p\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16647, \"clang_revision\": \"llvmorg-12-init-15676-g5c951623-1\", \"got_angle_revision\": \"e91097bf3a24895f23b928ed12087dcbb1d54d7b\", \"got_dawn_revision\": \"200941c797512895039e9f0315fc18fd9d9734ad\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"95d619c574cde934211e74cd2ed46a0de988ca0c\", \"got_revision_cp\": \"refs/heads/master@{#840447}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"b9e179f1f239bd1da8fffbd997a41095345d1f6a\", \"got_v8_revision\": \"c8d1d1cbceae8f59975b2ec7d86981ec3fabb11b\", \"got_v8_revision_cp\": \"refs/heads/8.9.212@{#1}\", \"got_webrtc_revision\": \"fc6b87192db6d5e4fd6997814f00b6792cf16180\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32904}\", \"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\": \"95d619c574cde934211e74cd2ed46a0de988ca0c\", \"swarm_hashes_refs/heads/master(at){#840447}_without_patch\": {\"absl_hardening_tests\": \"3619ef4facb109dfd951d838ac3e1b0258d403d5\", \"accessibility_unittests\": \"0eb0b8f0fc0c58bf856d9dcdeead5d5ca8357857\", \"angle_unittests\": \"aebda427d0f9965cbb533c3d733f3ded1b56f58d\", \"app_shell_unittests\": \"c0db69f5cebdaa2b46a6c090a341d544b90f9750\", \"aura_unittests\": \"056223017e300af24616ecad8b3b456ece08979c\", \"base_unittests\": \"799d9ee80da31d843bb00b6961d3781e64a0c1d7\", \"base_util_unittests\": \"92dcb6077b410679a3eecfced202e269fbf2fdac\", \"blink_common_unittests\": \"2e1b6b4be9215742e2a947fdcb8cd935d457fbb4\", \"blink_fuzzer_unittests\": \"4151987e22bab068351f5cdf52c4e2006905724e\", \"blink_heap_unittests\": \"c96399ec4bcf352a76980ba2a56eecde7ac3834f\", \"blink_platform_unittests\": \"de132abfa536224beea4a3884519f248060afddb\", \"blink_unittests\": \"745c7e431f0475b8e1529e4b3f1bcb3cddc83627\", \"boringssl_crypto_tests\": \"b8a17d0d3dd7c5abeb86d6f5510a737c5b74056e\", \"boringssl_ssl_tests\": \"f053e0c96e683ddf41e94fb6a65da79f6d77b655\", \"browser_switcher_bho_unittests\": \"90f11e461d5eabf0800a1e5173e5f2c63cb63d46\", \"browser_tests\": \"efa98c84633cfe61dc3ce1fbd021225a05422a62\", \"capture_unittests\": \"749c489c42a9973cf585b24a3bbf64b6ae435f06\", \"cast_unittests\": \"781d73ca24680c8bfbbae1e9993ce6d336d2f96d\", \"cc_unittests\": \"c77f26e57981ed8e1875526dc141d2d272eb521b\", \"chrome_app_unittests\": \"310855fb98dc3e2b7f7ff1c0b0211640d6502143\", \"chrome_cleaner_unittests\": \"8c43d636863ffa453d6ca675ac3e65276746f44b\", \"chrome_elf_unittests\": \"e646e78b5de3dd2cebca381c2e3b4e00362cdb55\", \"chromedriver_unittests\": \"fc2ee7eff0fcc717fdebf913a8b1a46df5b41836\", \"components_browsertests\": \"9b11e0b8b1b21c4b0fd6e8c04883f9b3c6e19a7a\", \"components_unittests\": \"3f519b8ed54ffa39837d2c9a733f481cf20f270e\", \"compositor_unittests\": \"d64d3a00173b92fcac9c225b96cb91686d946cc1\", \"content_browsertests\": \"2b6bcc3f8bbb6e5d0c94fd8e7100e130e1607494\", \"content_unittests\": \"001380dd8bd4253943e15fa47cf0f6e6b80d9519\", \"courgette_unittests\": \"bd40b14374057e10e0644c3256c0439f7ac0fb8d\", \"crashpad_tests\": \"08197af72fa205832c6c5ad7841164b36262fc44\", \"cronet_tests\": \"a32411c85bea054e9374d7446657fcfca4051eb4\", \"cronet_unittests\": \"61d03137c3a12ada44d5709bba0f88733aa3514e\", \"crypto_unittests\": \"9040a2cd330e80aed96a2267ad06b577926bc848\", \"delayloads_unittests\": \"45a99c3c697e55fb90c71079dd273719b7072ee9\", \"device_unittests\": \"b2ff4fd2e214b723eac42b757784f36843769f00\", \"display_unittests\": \"f113a9eccff10ff40a06be4c2592146b884e7162\", \"elevation_service_unittests\": \"088096c7e0aa2b7db7d18f2615b34772951d1b8e\", \"events_unittests\": \"3167a9ff2b7bdafd3c63b2c78f35f7ce5f1d7e48\", \"extensions_browsertests\": \"3192c15ef5936d1153f9c8ef7a78f3c07b0c25a1\", \"extensions_unittests\": \"d6bee289da49efb1192c0013d5c57de1d51e8310\", \"filesystem_service_unittests\": \"96ecc3cadefddc45eb1a61d6558c4cd606d9283b\", \"gcm_unit_tests\": \"f00a29e78cadb05f1fa8d69793c0f2148c2da441\", \"gcp_unittests\": \"c9ca361bb134396c6573a6a28b9693cca275b9ac\", \"gfx_unittests\": \"eeef39217c5c6cb9817a07780893022019a952f1\", \"gin_unittests\": \"679e706fe2b1dcb8a9d650056c0f0738de5256a6\", \"google_apis_unittests\": \"171fae29bdea696cfa739e11249a8cae01757c8c\", \"gpu_unittests\": \"26836dfcc1de4c8d81773b853feb02339316acd9\", \"gwp_asan_unittests\": \"78b1d7d1726c5a7d381c2b85cad0689c81cf9df2\", \"headless_browsertests\": \"d9598c9b61371f0722dfdbe1aa3d8293aef0ef52\", \"headless_unittests\": \"b301900368b39493bc9ab518bdbd82065e3a2548\", \"install_static_unittests\": \"bba1eb5f87ec20515722f3232ea26ecd0f21273e\", \"installer_util_unittests\": \"1a82d1c4058a18b768f12bf5c417f7c719533f45\", \"interactive_ui_tests\": \"cfb5e5cf9a1593bf17c35bdfc8af66ea9f2770ff\", \"ipc_tests\": \"732e63e2f46d6c99cf4dee4bfa3a80dc0a97de99\", \"jingle_unittests\": \"7a10c28f236a9b0ad39c43c554ac1a2ed7b3addf\", \"latency_unittests\": \"1db8dce8ffcc6f59e06db5cbb3c3ec788e3901b5\", \"libjingle_xmpp_unittests\": \"b0b25c48ef69be7f587b6c9bd5b707baa56d468b\", \"liburlpattern_unittests\": \"578a045624676b7e7e8f995714e6133b9828be61\", \"media_blink_unittests\": \"36db2de64223fad575968de7b38aa25d6315d0cb\", \"media_unittests\": \"64e182d656508f9cb48e4eefa61c0ec721d86100\", \"message_center_unittests\": \"b98d63b3f4055695ffb69957ac4209ec05df67f0\", \"midi_unittests\": \"2c8e5b52ce991b1a3d4c05b1d2931d2a0e445efd\", \"mojo_core_unittests\": \"8bd16ed9aac740129101138402e00a44c97654a0\", \"mojo_unittests\": \"661488f0e43888e1a9b4e7e4be9ccea40f994b6a\", \"nacl_loader_unittests\": \"dc06a4cd67b2e27b70b5d7addc6c60cfa493ef52\", \"native_theme_unittests\": \"9dff1778b9aa08218b95099006c25b03b8f64a82\", \"net_unittests\": \"7a6fd3e837e753adb708a6c6bb831d49a73490b2\", \"notification_helper_unittests\": \"6a538d36de0ebe3e0ae9068d1c86a2660a005f2b\", \"pdf_unittests\": \"a5ec3abd48d8b37362ba4e5f6eed06f38ae639c9\", \"perfetto_unittests\": \"10b9db2156700818a88fc387c85e8565517f356d\", \"ppapi_unittests\": \"28946094a9e75b5b239d55e772a255e86429c2b7\", \"printing_unittests\": \"fd187b5ebf45b48acea4edb1103ab31b3b2d81cc\", \"remoting_unittests\": \"5a0e24a03c2b6e097750d41dd91e42923f649494\", \"sbox_integration_tests\": \"5d5a8944f0e488422c37335ec61073813a6c10c3\", \"sbox_unittests\": \"b0686360878859fbf452f012cbcccbe9f146ad22\", \"sbox_validation_tests\": \"38354a81400ee4ab32c757ddc30df687dacf4df5\", \"service_manager_unittests\": \"c970881c44dc6cebdfbf640cf37881b539aaa3a0\", \"services_unittests\": \"45239ac71924e7b1542e06d541f9966ed09e66f4\", \"setup_unittests\": \"e73e76cb58674508789ddb60b4cb9901ae22af4a\", \"shell_dialogs_unittests\": \"2e67bde14212a676507bc897fcbaa7da31d3188d\", \"skia_unittests\": \"52b7fc8c2e282a7ef6d351e9a61cc738f23dd68a\", \"snapshot_unittests\": \"10e2e4887c53e417fe245268eb4215086a883955\", \"sql_unittests\": \"a5771b73fbb9bd818aebb068a61d05edc1261fa1\", \"storage_unittests\": \"f10e6c67b8817c4d8713f3bee0f4a4ac2bc5b45c\", \"sync_integration_tests\": \"4667fc50b9f45ee251838f212a9a5d6f1fe61eb7\", \"traffic_annotation_auditor_unittests\": \"a09e70cd73955f0adaea838afd7b92e9802e8cc2\", \"ui_base_unittests\": \"adf85282663b6d106a850b2b83ef1aad9a0e345c\", \"ui_touch_selection_unittests\": \"826e61b67588fd39b3b4fbfc18509d9a668f8088\", \"unit_tests\": \"bb60ed2adfa611ccb9fb0e69315e034a7267923c\", \"updater_tests\": \"440e19c62fd0795d3efba01a3dfb173a06feaa94\", \"url_unittests\": \"c18937a95e8a2c8c203bdadc0713d0374452ed58\", \"views_unittests\": \"9fd86ae68393240ccc570182b9b2314334151380\", \"viz_unittests\": \"ac05caf13cd2c558d57f977a3e03df17cca300f5\", \"vr_common_unittests\": \"51f7a77062d5fed22e517b43cf371dd5f51cb089\", \"vr_pixeltests\": \"c3dacbdef8aa6a002946da1e769e3e263d7a1d4f\", \"weblayer_browsertests\": \"4be0ec5ab68cec673a3902792b4ea70f24e50214\", \"weblayer_unittests\": \"750399095b39aed4c99a326ec7f2bd7bfca4f40b\", \"wm_unittests\": \"3c12d37776840401c96e1ba1ff769ff0ba045af8\", \"wtf_unittests\": \"c6cbe20cd732d1fe7cd9db6671b7b78e434a2dde\", \"zlib_unittests\": \"edcf67a8c9c818f57fef9e3db8dad88a48273a46\", \"zucchini_unittests\": \"eb84c50a548da171675a928df5c27fc4b9d08a07\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-us-central1-b-32-0-fy7p\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"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\": [\"5657-0417a3f\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**content_browsertests** failed because of:\\n\\n- All/StartupTracingTest.TestEnableTracing/7\\n\\n- All/StartupTracingTest.TestEnableTracing/6\\n\\n- All/StartupTracingTest.TestEnableTracing/8\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1609900039417302",
"status": "COMPLETED",
"status_changed_ts": "1609900041450457",
"tags": [
"build_address:luci.chromium.ci/win-asan/16647",
"builder:win-asan",
"buildset:commit/git/95d619c574cde934211e74cd2ed46a0de988ca0c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/95d619c574cde934211e74cd2ed46a0de988ca0c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024321510410413328",
"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/8858891448353804160/+/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:50ee52ac1dbef910",
"user_agent:luci-scheduler"
],
"updated_ts": "1609906339033917",
"url": "https://ci.chromium.org/b/8858891448353804160",
"utcnow_ts": "1609906339735795"
}
}
[04:12:19.757] Invocation finished in 1h49m25.019859221s with status FAILED