[23:06:30.216] New invocation is queued and will start shortly
[23:06:31.371] Starting the invocation (attempt 1)
[23:06:31.406] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:06:31.406] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:06:31.406] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019894616038159696",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"5525ada9f8fd6d74c3d2264761cfd46f74044abf\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9019894616038159696",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/5525ada9f8fd6d74c3d2264761cfd46f74044abf",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5525ada9f8fd6d74c3d2264761cfd46f74044abf",
"gitiles_ref:refs/heads/master"
]
}
[23:06:32.149] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614121591500936",
"id": "8854464554029241312",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5525ada9f8fd6d74c3d2264761cfd46f74044abf\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614121591902313",
"tags": [
"build_address:luci.chromium.ci/win-asan/17498",
"builder:win-asan",
"buildset:commit/git/5525ada9f8fd6d74c3d2264761cfd46f74044abf",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5525ada9f8fd6d74c3d2264761cfd46f74044abf",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019894616038159696",
"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/8854464554029241312/+/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": "1614121591902422",
"url": "https://ci.chromium.org/b/8854464554029241312",
"utcnow_ts": "1614121592141912"
}
}
[23:06:32.149] Task URL: https://ci.chromium.org/b/8854464554029241312
[23:06:32.149] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:2:0) after 1m0s
[23:07:29.548] Received PubSub notification, asking Buildbucket for the build status
[23:07:29.659] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:32.151] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:2:0)
[23:07:32.151] Timer tick, asking Buildbucket for the build status
[23:07:32.243] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:32.243] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:4:0) after 1m0s
[23:08:32.328] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:4:0)
[23:08:32.328] Timer tick, asking Buildbucket for the build status
[23:08:32.404] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:32.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:5:0) after 1m0s
[23:09:32.472] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:5:0)
[23:09:32.472] Timer tick, asking Buildbucket for the build status
[23:09:32.565] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:32.565] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:6:0) after 1m0s
[23:10:32.617] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:6:0)
[23:10:32.617] Timer tick, asking Buildbucket for the build status
[23:10:32.702] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:32.702] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:7:0) after 1m0s
[23:11:32.755] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:7:0)
[23:11:32.755] Timer tick, asking Buildbucket for the build status
[23:11:33.099] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:33.099] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:8:0) after 1m0s
[23:12:33.259] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:8:0)
[23:12:33.259] Timer tick, asking Buildbucket for the build status
[23:12:33.355] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:33.355] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:9:0) after 1m0s
[23:13:33.526] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:9:0)
[23:13:33.526] Timer tick, asking Buildbucket for the build status
[23:13:33.632] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:33.632] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:10:0) after 1m0s
[23:14:33.701] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:10:0)
[23:14:33.701] Timer tick, asking Buildbucket for the build status
[23:14:33.772] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:33.772] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:11:0) after 1m0s
[23:15:33.875] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:11:0)
[23:15:33.875] Timer tick, asking Buildbucket for the build status
[23:15:33.955] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:33.955] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:12:0) after 1m0s
[23:16:34.083] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:12:0)
[23:16:34.083] Timer tick, asking Buildbucket for the build status
[23:16:34.169] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:34.169] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:13:0) after 1m0s
[23:17:34.322] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:13:0)
[23:17:34.322] Timer tick, asking Buildbucket for the build status
[23:17:34.403] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:34.403] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:14:0) after 1m0s
[23:18:34.460] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:14:0)
[23:18:34.460] Timer tick, asking Buildbucket for the build status
[23:18:34.619] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:34.619] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:15:0) after 1m0s
[23:19:34.647] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:15:0)
[23:19:34.647] Timer tick, asking Buildbucket for the build status
[23:19:34.728] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:34.728] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:16:0) after 1m0s
[23:20:34.813] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:16:0)
[23:20:34.813] Timer tick, asking Buildbucket for the build status
[23:20:35.120] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:35.120] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:17:0) after 1m0s
[23:21:35.163] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:17:0)
[23:21:35.163] Timer tick, asking Buildbucket for the build status
[23:21:35.349] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:35.349] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:18:0) after 1m0s
[23:22:35.381] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:18:0)
[23:22:35.381] Timer tick, asking Buildbucket for the build status
[23:22:35.546] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:35.546] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:19:0) after 1m0s
[23:23:35.632] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:19:0)
[23:23:35.632] Timer tick, asking Buildbucket for the build status
[23:23:35.979] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:35.979] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:20:0) after 1m0s
[23:24:36.063] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:20:0)
[23:24:36.063] Timer tick, asking Buildbucket for the build status
[23:24:36.138] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:36.138] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:21:0) after 1m0s
[23:25:36.333] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:21:0)
[23:25:36.333] Timer tick, asking Buildbucket for the build status
[23:25:36.437] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:36.437] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:22:0) after 1m0s
[23:26:36.609] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:22:0)
[23:26:36.609] Timer tick, asking Buildbucket for the build status
[23:26:36.914] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:36.914] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:23:0) after 1m0s
[23:27:36.968] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:23:0)
[23:27:36.968] Timer tick, asking Buildbucket for the build status
[23:27:37.052] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:37.052] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:24:0) after 1m0s
[23:28:37.206] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:24:0)
[23:28:37.206] Timer tick, asking Buildbucket for the build status
[23:28:37.740] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:37.740] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:25:0) after 1m0s
[23:29:37.770] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:25:0)
[23:29:37.770] Timer tick, asking Buildbucket for the build status
[23:29:38.010] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:38.010] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:26:0) after 1m0s
[23:30:38.029] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:26:0)
[23:30:38.029] Timer tick, asking Buildbucket for the build status
[23:30:38.302] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:38.302] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:27:0) after 1m0s
[23:31:38.490] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:27:0)
[23:31:38.490] Timer tick, asking Buildbucket for the build status
[23:31:38.588] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:38.588] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:28:0) after 1m0s
[23:32:38.685] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:28:0)
[23:32:38.685] Timer tick, asking Buildbucket for the build status
[23:32:38.753] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:38.753] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:29:0) after 1m0s
[23:33:38.823] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:29:0)
[23:33:38.823] Timer tick, asking Buildbucket for the build status
[23:33:38.897] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:38.897] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:30:0) after 1m0s
[23:34:39.037] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:30:0)
[23:34:39.037] Timer tick, asking Buildbucket for the build status
[23:34:39.109] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:39.109] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:31:0) after 1m0s
[23:35:39.194] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:31:0)
[23:35:39.194] Timer tick, asking Buildbucket for the build status
[23:35:39.286] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:39.286] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:32:0) after 1m0s
[23:36:39.341] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:32:0)
[23:36:39.341] Timer tick, asking Buildbucket for the build status
[23:36:39.756] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:39.756] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:33:0) after 1m0s
[23:37:39.849] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:33:0)
[23:37:39.849] Timer tick, asking Buildbucket for the build status
[23:37:39.941] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:39.941] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:34:0) after 1m0s
[23:38:39.958] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:34:0)
[23:38:39.958] Timer tick, asking Buildbucket for the build status
[23:38:40.160] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:40.160] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:35:0) after 1m0s
[23:39:40.254] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:35:0)
[23:39:40.254] Timer tick, asking Buildbucket for the build status
[23:39:40.349] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:40.349] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:36:0) after 1m0s
[23:40:41.406] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:36:0)
[23:40:41.406] Timer tick, asking Buildbucket for the build status
[23:40:41.481] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:41.481] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:37:0) after 1m0s
[23:41:41.570] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:37:0)
[23:41:41.570] Timer tick, asking Buildbucket for the build status
[23:41:41.761] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:41.761] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:38:0) after 1m0s
[23:42:41.909] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:38:0)
[23:42:41.909] Timer tick, asking Buildbucket for the build status
[23:42:41.990] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:41.990] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:39:0) after 1m0s
[23:43:42.117] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:39:0)
[23:43:42.117] Timer tick, asking Buildbucket for the build status
[23:43:42.333] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:42.333] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:40:0) after 1m0s
[23:44:42.427] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:40:0)
[23:44:42.427] Timer tick, asking Buildbucket for the build status
[23:44:42.820] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:42.820] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:41:0) after 1m0s
[23:45:42.921] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:41:0)
[23:45:42.921] Timer tick, asking Buildbucket for the build status
[23:45:42.996] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:42.996] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:42:0) after 1m0s
[23:46:43.062] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:42:0)
[23:46:43.062] Timer tick, asking Buildbucket for the build status
[23:46:43.162] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:43.162] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:43:0) after 1m0s
[23:47:43.199] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:43:0)
[23:47:43.199] Timer tick, asking Buildbucket for the build status
[23:47:43.386] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:43.386] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:44:0) after 1m0s
[23:48:43.449] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:44:0)
[23:48:43.449] Timer tick, asking Buildbucket for the build status
[23:48:43.608] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:43.608] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:45:0) after 1m0s
[23:49:43.693] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:45:0)
[23:49:43.693] Timer tick, asking Buildbucket for the build status
[23:49:43.846] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:43.846] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:46:0) after 1m0s
[23:50:44.379] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:46:0)
[23:50:44.379] Timer tick, asking Buildbucket for the build status
[23:50:44.467] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:44.467] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:47:0) after 1m0s
[23:51:44.575] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:47:0)
[23:51:44.575] Timer tick, asking Buildbucket for the build status
[23:51:44.740] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:44.740] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:48:0) after 1m0s
[23:52:44.774] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:48:0)
[23:52:44.774] Timer tick, asking Buildbucket for the build status
[23:52:44.914] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:44.914] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:49:0) after 1m0s
[23:53:45.026] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:49:0)
[23:53:45.026] Timer tick, asking Buildbucket for the build status
[23:53:45.117] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:45.117] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:50:0) after 1m0s
[23:54:45.234] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:50:0)
[23:54:45.234] Timer tick, asking Buildbucket for the build status
[23:54:45.366] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:45.366] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:51:0) after 1m0s
[23:55:45.548] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:51:0)
[23:55:45.548] Timer tick, asking Buildbucket for the build status
[23:55:45.698] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:45.698] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:52:0) after 1m0s
[23:56:45.800] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:52:0)
[23:56:45.800] Timer tick, asking Buildbucket for the build status
[23:56:46.103] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:46.103] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:53:0) after 1m0s
[23:57:46.150] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:53:0)
[23:57:46.150] Timer tick, asking Buildbucket for the build status
[23:57:46.310] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:46.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:54:0) after 1m0s
[23:58:46.328] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:54:0)
[23:58:46.328] Timer tick, asking Buildbucket for the build status
[23:58:46.409] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:46.409] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:55:0) after 1m0s
[23:59:46.534] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:55:0)
[23:59:46.534] Timer tick, asking Buildbucket for the build status
[23:59:46.609] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:46.609] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:56:0) after 1m0s
[00:00:48.407] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:56:0)
[00:00:48.407] Timer tick, asking Buildbucket for the build status
[00:00:48.515] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:48.515] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:57:0) after 1m0s
[00:01:48.561] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:57:0)
[00:01:48.561] Timer tick, asking Buildbucket for the build status
[00:01:48.983] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:48.983] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:58:0) after 1m0s
[00:02:49.023] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:58:0)
[00:02:49.023] Timer tick, asking Buildbucket for the build status
[00:02:49.133] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:49.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:59:0) after 1m0s
[00:03:49.231] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:59:0)
[00:03:49.231] Timer tick, asking Buildbucket for the build status
[00:03:49.337] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:49.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:60:0) after 1m0s
[00:04:49.448] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:60:0)
[00:04:49.448] Timer tick, asking Buildbucket for the build status
[00:04:49.862] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:49.862] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:61:0) after 1m0s
[00:05:49.966] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:61:0)
[00:05:49.966] Timer tick, asking Buildbucket for the build status
[00:05:50.063] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:50.063] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:62:0) after 1m0s
[00:06:50.093] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:62:0)
[00:06:50.093] Timer tick, asking Buildbucket for the build status
[00:06:50.294] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:50.294] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:63:0) after 1m0s
[00:07:50.341] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:63:0)
[00:07:50.341] Timer tick, asking Buildbucket for the build status
[00:07:50.502] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:50.502] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:64:0) after 1m0s
[00:08:50.529] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:64:0)
[00:08:50.529] Timer tick, asking Buildbucket for the build status
[00:08:50.633] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:50.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:65:0) after 1m0s
[00:09:50.617] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:65:0)
[00:09:50.617] Timer tick, asking Buildbucket for the build status
[00:09:50.780] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:50.780] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:66:0) after 1m0s
[00:10:50.838] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:66:0)
[00:10:50.838] Timer tick, asking Buildbucket for the build status
[00:10:50.959] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:50.959] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:67:0) after 1m0s
[00:11:51.001] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:67:0)
[00:11:51.001] Timer tick, asking Buildbucket for the build status
[00:11:51.164] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:51.164] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:68:0) after 1m0s
[00:12:51.197] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:68:0)
[00:12:51.197] Timer tick, asking Buildbucket for the build status
[00:12:51.427] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:51.427] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:69:0) after 1m0s
[00:13:51.446] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:69:0)
[00:13:51.446] Timer tick, asking Buildbucket for the build status
[00:13:52.399] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:52.399] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:70:0) after 1m0s
[00:14:52.471] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:70:0)
[00:14:52.471] Timer tick, asking Buildbucket for the build status
[00:14:52.701] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:52.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:71:0) after 1m0s
[00:15:52.798] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:71:0)
[00:15:52.798] Timer tick, asking Buildbucket for the build status
[00:15:53.090] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:53.090] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:72:0) after 1m0s
[00:16:53.173] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:72:0)
[00:16:53.173] Timer tick, asking Buildbucket for the build status
[00:16:53.767] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:53.767] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:73:0) after 1m0s
[00:17:53.948] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:73:0)
[00:17:53.948] Timer tick, asking Buildbucket for the build status
[00:17:54.312] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:54.312] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:74:0) after 1m0s
[00:18:54.392] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:74:0)
[00:18:54.392] Timer tick, asking Buildbucket for the build status
[00:18:54.493] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:54.493] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:75:0) after 1m0s
[00:19:54.490] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:75:0)
[00:19:54.490] Timer tick, asking Buildbucket for the build status
[00:19:54.584] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:54.584] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:76:0) after 1m0s
[00:20:54.598] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:76:0)
[00:20:54.598] Timer tick, asking Buildbucket for the build status
[00:20:54.786] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:54.786] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:77:0) after 1m0s
[00:21:54.866] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:77:0)
[00:21:54.866] Timer tick, asking Buildbucket for the build status
[00:21:55.017] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:55.017] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:78:0) after 1m0s
[00:22:55.048] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:78:0)
[00:22:55.048] Timer tick, asking Buildbucket for the build status
[00:22:55.420] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:55.420] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:79:0) after 1m0s
[00:23:55.425] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:79:0)
[00:23:55.425] Timer tick, asking Buildbucket for the build status
[00:23:55.598] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:55.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:80:0) after 1m0s
[00:24:55.658] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:80:0)
[00:24:55.658] Timer tick, asking Buildbucket for the build status
[00:24:55.946] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:55.946] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:81:0) after 1m0s
[00:25:55.968] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:81:0)
[00:25:55.968] Timer tick, asking Buildbucket for the build status
[00:25:56.067] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:56.067] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:82:0) after 1m0s
[00:26:56.147] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:82:0)
[00:26:56.147] Timer tick, asking Buildbucket for the build status
[00:26:56.285] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:56.285] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:83:0) after 1m0s
[00:27:56.355] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:83:0)
[00:27:56.355] Timer tick, asking Buildbucket for the build status
[00:27:56.469] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:56.469] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:84:0) after 1m0s
[00:28:56.577] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:84:0)
[00:28:56.577] Timer tick, asking Buildbucket for the build status
[00:28:56.810] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:56.810] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:85:0) after 1m0s
[00:29:56.885] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:85:0)
[00:29:56.885] Timer tick, asking Buildbucket for the build status
[00:29:56.967] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:56.967] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:86:0) after 1m0s
[00:30:56.961] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:86:0)
[00:30:56.961] Timer tick, asking Buildbucket for the build status
[00:30:57.059] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:57.059] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:87:0) after 1m0s
[00:31:57.168] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:87:0)
[00:31:57.168] Timer tick, asking Buildbucket for the build status
[00:31:58.119] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:58.119] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:88:0) after 1m0s
[00:32:58.184] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:88:0)
[00:32:58.184] Timer tick, asking Buildbucket for the build status
[00:32:58.303] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:58.303] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:89:0) after 1m0s
[00:33:58.310] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:89:0)
[00:33:58.310] Timer tick, asking Buildbucket for the build status
[00:33:58.401] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:58.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:90:0) after 1m0s
[00:34:58.468] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:90:0)
[00:34:58.468] Timer tick, asking Buildbucket for the build status
[00:34:58.682] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:58.682] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:91:0) after 1m0s
[00:35:58.727] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:91:0)
[00:35:58.727] Timer tick, asking Buildbucket for the build status
[00:35:59.306] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:59.306] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:92:0) after 1m0s
[00:36:59.348] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:92:0)
[00:36:59.348] Timer tick, asking Buildbucket for the build status
[00:36:59.515] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:59.515] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:93:0) after 1m0s
[00:37:59.583] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:93:0)
[00:37:59.583] Timer tick, asking Buildbucket for the build status
[00:37:59.752] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:59.752] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:94:0) after 1m0s
[00:38:59.893] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:94:0)
[00:38:59.893] Timer tick, asking Buildbucket for the build status
[00:38:59.974] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:59.974] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:95:0) after 1m0s
[00:40:00.178] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:95:0)
[00:40:00.178] Timer tick, asking Buildbucket for the build status
[00:40:00.318] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:00.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:96:0) after 1m0s
[00:41:01.074] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:96:0)
[00:41:01.074] Timer tick, asking Buildbucket for the build status
[00:41:01.213] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:01.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:97:0) after 1m0s
[00:42:01.511] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:97:0)
[00:42:01.511] Timer tick, asking Buildbucket for the build status
[00:42:01.624] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:01.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:98:0) after 1m0s
[00:43:01.647] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:98:0)
[00:43:01.647] Timer tick, asking Buildbucket for the build status
[00:43:01.759] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:01.759] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:99:0) after 1m0s
[00:44:01.940] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:99:0)
[00:44:01.940] Timer tick, asking Buildbucket for the build status
[00:44:02.030] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:02.030] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:100:0) after 1m0s
[00:45:02.093] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:100:0)
[00:45:02.093] Timer tick, asking Buildbucket for the build status
[00:45:02.183] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:02.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:101:0) after 1m0s
[00:46:02.170] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:101:0)
[00:46:02.170] Timer tick, asking Buildbucket for the build status
[00:46:02.316] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:02.316] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:102:0) after 1m0s
[00:47:02.382] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:102:0)
[00:47:02.382] Timer tick, asking Buildbucket for the build status
[00:47:03.037] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:03.037] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:103:0) after 1m0s
[00:48:03.031] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:103:0)
[00:48:03.031] Timer tick, asking Buildbucket for the build status
[00:48:03.148] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:03.149] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:104:0) after 1m0s
[00:49:03.173] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:104:0)
[00:49:03.173] Timer tick, asking Buildbucket for the build status
[00:49:03.377] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:03.377] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:105:0) after 1m0s
[00:50:03.419] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:105:0)
[00:50:03.419] Timer tick, asking Buildbucket for the build status
[00:50:04.011] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:04.011] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:106:0) after 1m0s
[00:51:04.073] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:106:0)
[00:51:04.073] Timer tick, asking Buildbucket for the build status
[00:51:04.170] Build 8854464554029241312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:04.170] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019894616038159696:107:0) after 1m0s
[00:51:33.624] Received PubSub notification, asking Buildbucket for the build status
[00:51:33.722] Build 8854464554029241312: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[00:51:33.722] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614127893328807",
"created_by": "project:chromium",
"created_ts": "1614121591500936",
"failure_reason": "BUILD_FAILURE",
"id": "8854464554029241312",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5525ada9f8fd6d74c3d2264761cfd46f74044abf\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**content_browsertests** failed because of:\\n\\n- ScrollLatencyBrowserTest.MultipleWheelScroll\"}, \"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\": \"5525ada9f8fd6d74c3d2264761cfd46f74044abf\", \"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-5-vp6c\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17498, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-2\", \"got_angle_revision\": \"f19f319a59c83d9ccdce08a3b41024cfc997fd8d\", \"got_dawn_revision\": \"232f4d183ce8ab93909b30af814e320c0f0db57c\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"5525ada9f8fd6d74c3d2264761cfd46f74044abf\", \"got_revision_cp\": \"refs/heads/master@{#856902}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"22186f076bf3bbe1a818792bde93b297e3773f7e\", \"got_v8_revision\": \"27ecbe09941902be5c7e57493ca440f45bf8218e\", \"got_v8_revision_cp\": \"refs/heads/9.0.240@{#1}\", \"got_webrtc_revision\": \"d0844a80deaab28d7df8e5dff9f71e6ab6919025\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33323}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.win:win-asan\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5525ada9f8fd6d74c3d2264761cfd46f74044abf\", \"swarm_hashes_refs/heads/master(at){#856902}_without_patch\": {\"absl_hardening_tests\": \"c09b678be0ed806b6f0edbcdb13730c9d2b429e2\", \"accessibility_unittests\": \"5101e6a34a09afc0443cb46de5c12f3003038699\", \"angle_unittests\": \"9a33f2e51748be2af06bd7a07675f97ad4b9644a\", \"app_shell_unittests\": \"7f42781681db694cb004c33a4c614ae79416f08d\", \"aura_unittests\": \"0771ececac8f8e5c6fc217eaec51e834bb569ceb\", \"base_unittests\": \"edce996fca72ca71b3588db92b36e18ea8940f25\", \"base_util_unittests\": \"a941540828cdc9294a8f290e1136da7297fe21f9\", \"blink_common_unittests\": \"bc931bb371edb342be2386e187f7b7cf4b9350a3\", \"blink_fuzzer_unittests\": \"f2dbd97dd95cea821d45913499093f68f19d51a5\", \"blink_heap_unittests\": \"7edd91d549c5188036e1666e18dc979a0837fcc7\", \"blink_platform_unittests\": \"1408810df5df59ac166197ce57a844a82c55015a\", \"blink_unittests\": \"7f13fa466429b83c7db0d06418f257ccbfb9b025\", \"boringssl_crypto_tests\": \"eaffa5d12adf692bdb7e8fa939898fe908aba020\", \"boringssl_ssl_tests\": \"b90a5dcfcf288c06f1c7b510298b340cccdb39bf\", \"browser_switcher_bho_unittests\": \"502055f7ba822262a52281d357953ba709b5880c\", \"browser_tests\": \"2935b95f669a87e9b41105260f2d06f9961119f2\", \"capture_unittests\": \"7f0f3b80d35470a74c327a2c8407ec904ee715bf\", \"cast_unittests\": \"ad6dbce2830fe4666d2abb535b3fd8bd6e7fbab3\", \"cc_unittests\": \"07f80d5e4e39cca217a7f4eafdc121394842af8b\", \"chrome_app_unittests\": \"b733af81bdfb9527fc6bfc8eee81525f3920fb7c\", \"chrome_cleaner_unittests\": \"d9cadd48e781f9762585e667fcf87b06c4ae2f90\", \"chrome_elf_unittests\": \"bebd8e6c05e63230cf77e49aaf2b1d5d23bf5dc8\", \"chromedriver_unittests\": \"82b17f8c8fc5ed30540a84a36f680d1138244a6d\", \"components_browsertests\": \"8bb1ea9ab49ab6aeccfb992967a2f640cd4d11de\", \"components_unittests\": \"e9d366d3fd9cb5476b4a43e97bf7f4fe7258d14c\", \"compositor_unittests\": \"2939f04ae75a7955126fe058211c1c78b14abf92\", \"content_browsertests\": \"42f742cd5689bd3e5890e8c9eb65364b6b19f252\", \"content_unittests\": \"fa5ed223677a8837ffe966858514fe642a3920b8\", \"courgette_unittests\": \"5e5f411c1842bcda1df7c4881aeac1fe9c2d6db5\", \"crashpad_tests\": \"02299e5d3d0577ee61f53495d06d069e4d20744e\", \"cronet_tests\": \"a4635c763890af63ad7a70a7749dd18b6e2ebdc3\", \"cronet_unittests\": \"290eef0c0b064ed963fe5551db73e82792fef963\", \"crypto_unittests\": \"77790055e53a525dc18193822ac154df6e0e7705\", \"delayloads_unittests\": \"b92fee426a6cf7dd863eaba74c009dfb398f6898\", \"device_unittests\": \"3b234d2fc226095af4ab3e97942ea17d841d33e2\", \"display_unittests\": \"29753bd24367f292c132e59628742beb84d2aa1f\", \"elevation_service_unittests\": \"aa782d78beb22833a59514ff3d4e4f398d2a426d\", \"events_unittests\": \"2b7fa52ccda4e08d7f1aca4bbd32c5dfbc14a976\", \"extensions_browsertests\": \"667ba7d207771c01370ebed93a24a67926a62bff\", \"extensions_unittests\": \"c623cd7f1340ae755167b22a7dcce19ab8486e0b\", \"filesystem_service_unittests\": \"20f491b912ef899c049bd23207fba6e0bc4c21b0\", \"gcm_unit_tests\": \"e5c5d58cccefd8d9b25746c1a7028ef11cd8cdc5\", \"gcp_unittests\": \"7616cb2b3c2aab35ce9b6ef6957861cc22ff6906\", \"gfx_unittests\": \"35005a2809d6872826204a19348656dd95fdce10\", \"gin_unittests\": \"e47ce6fbdee704a7f1d4ba08bee07fbf968bb882\", \"google_apis_unittests\": \"2bb6d2fab9a57a8a4b904eb2b386f28565279d77\", \"gpu_unittests\": \"2cc5a588854c5603ac6925add38bd8e71f084c27\", \"gwp_asan_unittests\": \"93e7dedd71ad06ca4e9731928c85687ae8303eb8\", \"headless_browsertests\": \"1f031f5ed48c374bc11676e71bd8afd2e1e1e2fd\", \"headless_unittests\": \"865787a2282932e4543beec72c17cf8d63aafed7\", \"install_static_unittests\": \"85020ea57cbd0ce57d0dd855b51c7daf9719d4a6\", \"installer_util_unittests\": \"47dbc90b1f52750ad3f936d91405ae0c89e6cc46\", \"interactive_ui_tests\": \"ff423067d6f44497fe40d3b255007836e959671d\", \"ipc_tests\": \"8305f256237bbeb0959ce8765701336eb54f4b05\", \"jingle_unittests\": \"decad922e7f3af1e50f6eefdf788eaba93db05ea\", \"latency_unittests\": \"5c8233b237bab46e00c2dfd1f09b0f981a84c25a\", \"libjingle_xmpp_unittests\": \"e6239c02e2291c91229ce649daa90d9f723d95a5\", \"liburlpattern_unittests\": \"e1d060681d119bdfdecd08e6d7df8f4a11125da2\", \"media_blink_unittests\": \"f469871d6cfd8a78b1c10b01918a7f643919bf6d\", \"media_unittests\": \"c7ebac2d0c758e273dcc7cb11b697b07b7bfb369\", \"message_center_unittests\": \"3d4015f1d98a173ab657ba75102a879a4b0b5ea7\", \"midi_unittests\": \"d04f8ba7d6ab9003b099c469305791ef5e9857b9\", \"mojo_core_unittests\": \"d89a4400c4185bf9aad33000eee2b4b2eeb3fe06\", \"mojo_unittests\": \"96c772513880db49c8c83baba332315fdb9bdd9f\", \"nacl_loader_unittests\": \"8937a57ad29967b8a3934bfc9e67fa2981610106\", \"native_theme_unittests\": \"6fd007349cd424822cb96591a03d26888430834b\", \"net_unittests\": \"291e7c9ffb0e6f324af719447f7e8db2435d6163\", \"notification_helper_unittests\": \"0f245c76a1266911d2d242e1699c76bbd01e3edf\", \"pdf_unittests\": \"d7ea21267f1103f04ded5916cf6bba0a9498538f\", \"perfetto_unittests\": \"69e39dd837ab596b3cd4b1694963355a1f38279b\", \"ppapi_unittests\": \"387a0412c61711356ff4fc64cd37230c213a552a\", \"printing_unittests\": \"493e04807c7972a938d68e13575e7bd8b0459795\", \"remoting_unittests\": \"bafd02c084b1d7ac07fe9c781def9727ab0e3a64\", \"sbox_integration_tests\": \"7f7d39e2bac7ce89ef60f1e0a4bd9250756285dc\", \"sbox_unittests\": \"cdb819388ceb08f4e441401c98816ca204167a9b\", \"sbox_validation_tests\": \"1c33e4b9e8aad7866111db259bf54c3a86256e77\", \"service_manager_unittests\": \"89e08540bcfd73862ae801610b1b7b5e22f93d94\", \"services_unittests\": \"f12cad762c61a162e239077d22228cb87c8c5efd\", \"setup_unittests\": \"8f5aa2d0c037c90187c2fbac5962ad9ead47b1b5\", \"shell_dialogs_unittests\": \"fe685d532ec282bd623d66ae7b14539d83df3b5e\", \"skia_unittests\": \"8171a6d169526b1a0bbca5ede879172f6c440383\", \"snapshot_unittests\": \"6421a93242e03b72923c6d5c5af5294a7c435198\", \"sql_unittests\": \"6dc93b26201fd4dc78f4db2274331a1b2a559899\", \"storage_unittests\": \"7326d54d2bdd0504a731fc8a0974a0b9e3a817c1\", \"sync_integration_tests\": \"a85d072eae2c2a674a1bbf955c5ccb9b1f12cc22\", \"traffic_annotation_auditor_unittests\": \"349e9c74ae1c6ec1243b4ff926301acfc05f1985\", \"ui_base_unittests\": \"01e3247a2f46ef13309f50820871d71f40c3fb5d\", \"ui_touch_selection_unittests\": \"a957dadac5080cc0cdddbf620f3ec7104834081b\", \"unit_tests\": \"e6225363d9475670568afbc0ea153bc71f1b86f8\", \"updater_tests\": \"bc786e7f1bb3d84e2e4c92505ce04af3d773b9bc\", \"url_unittests\": \"0a2697a04bc7438c2f43f7e2cd8451029093f106\", \"views_unittests\": \"37d8af5b70a27654b7c6221beb666d95b92ffdc7\", \"viz_unittests\": \"2bb288fdef84746fe21294ef617dcb9a68b7b027\", \"vr_common_unittests\": \"b54bd8c63bcd5a974463c2bdf1785c65448dca07\", \"vr_pixeltests\": \"0663c44178a45d66266515db5ef9cec5c7fa4082\", \"weblayer_browsertests\": \"33355ffa8966e4fc76bc71ca342aef415f87fb70\", \"weblayer_unittests\": \"6f4a03866fa1df0d9b1c54236322cc949f7877b8\", \"wm_unittests\": \"971e511606fef8b142678f43b81aa97148359d8c\", \"wtf_unittests\": \"2cc739b2dec6e71d682b8c4f53ecefdb04ca1807\", \"zlib_unittests\": \"a890387f5c9a88dc3667795f0feb5e093dc32e52\", \"zucchini_unittests\": \"bb881c14deea9e67d74c03ecccda6383108c6dfa\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-5-vp6c\"], \"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\": [\"5706-397711c\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**content_browsertests** failed because of:\\n\\n- ScrollLatencyBrowserTest.MultipleWheelScroll\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1614121648251132",
"status": "COMPLETED",
"status_changed_ts": "1614121648808349",
"tags": [
"build_address:luci.chromium.ci/win-asan/17498",
"builder:win-asan",
"buildset:commit/git/5525ada9f8fd6d74c3d2264761cfd46f74044abf",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5525ada9f8fd6d74c3d2264761cfd46f74044abf",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019894616038159696",
"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/8854464554029241312/+/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:51e9f66d9ebe7010",
"user_agent:luci-scheduler"
],
"updated_ts": "1614127893329192",
"url": "https://ci.chromium.org/b/8854464554029241312",
"utcnow_ts": "1614127893710275"
}
}
[00:51:33.722] Invocation finished in 1h45m3.520879533s with status FAILED