[22:45:33.625] New invocation is queued and will start shortly
[22:45:34.912] Starting the invocation (attempt 1)
[22:45:34.945] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:45:34.946] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:45:34.946] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9020348918501999408",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"1588199ead5a8b8778c8365c5eeef0847c58113d\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9020348918501999408",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/1588199ead5a8b8778c8365c5eeef0847c58113d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1588199ead5a8b8778c8365c5eeef0847c58113d",
"gitiles_ref:refs/heads/master"
]
}
[22:45:35.790] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1613688335027132",
"id": "8854918856368470192",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1588199ead5a8b8778c8365c5eeef0847c58113d\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1613688335489463",
"tags": [
"build_address:luci.chromium.ci/win-asan/17416",
"builder:win-asan",
"buildset:commit/git/1588199ead5a8b8778c8365c5eeef0847c58113d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1588199ead5a8b8778c8365c5eeef0847c58113d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020348918501999408",
"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/8854918856368470192/+/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": "1613688335489570",
"url": "https://ci.chromium.org/b/8854918856368470192",
"utcnow_ts": "1613688335743460"
}
}
[22:45:35.790] Task URL: https://ci.chromium.org/b/8854918856368470192
[22:45:35.790] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:2:0) after 1m0s
[22:46:35.838] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:2:0)
[22:46:35.838] Timer tick, asking Buildbucket for the build status
[22:46:36.224] Build 8854918856368470192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:46:36.224] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:3:0) after 1m0s
[22:47:36.229] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:3:0)
[22:47:36.229] Timer tick, asking Buildbucket for the build status
[22:47:36.303] Build 8854918856368470192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:47:36.303] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:4:0) after 1m0s
[22:48:23.741] Received PubSub notification, asking Buildbucket for the build status
[22:48:23.820] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:36.365] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:4:0)
[22:48:36.365] Timer tick, asking Buildbucket for the build status
[22:48:36.449] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:36.449] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:6:0) after 1m0s
[22:49:36.460] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:6:0)
[22:49:36.460] Timer tick, asking Buildbucket for the build status
[22:49:36.626] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:36.626] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:7:0) after 1m0s
[22:50:36.622] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:7:0)
[22:50:36.622] Timer tick, asking Buildbucket for the build status
[22:50:36.762] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:36.762] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:8:0) after 1m0s
[22:51:36.789] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:8:0)
[22:51:36.789] Timer tick, asking Buildbucket for the build status
[22:51:36.871] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:36.871] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:9:0) after 1m0s
[22:52:36.892] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:9:0)
[22:52:36.892] Timer tick, asking Buildbucket for the build status
[22:52:36.973] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:36.973] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:10:0) after 1m0s
[22:53:37.059] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:10:0)
[22:53:37.059] Timer tick, asking Buildbucket for the build status
[22:53:37.495] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:37.495] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:11:0) after 1m0s
[22:54:37.540] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:11:0)
[22:54:37.540] Timer tick, asking Buildbucket for the build status
[22:54:37.639] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:37.639] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:12:0) after 1m0s
[22:55:37.644] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:12:0)
[22:55:37.644] Timer tick, asking Buildbucket for the build status
[22:55:37.863] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:37.863] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:13:0) after 1m0s
[22:56:37.950] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:13:0)
[22:56:37.950] Timer tick, asking Buildbucket for the build status
[22:56:38.422] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:38.422] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:14:0) after 1m0s
[22:57:38.505] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:14:0)
[22:57:38.505] Timer tick, asking Buildbucket for the build status
[22:57:38.790] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:38.790] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:15:0) after 1m0s
[22:58:38.919] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:15:0)
[22:58:38.919] Timer tick, asking Buildbucket for the build status
[22:58:39.006] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:39.006] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:16:0) after 1m0s
[22:59:39.033] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:16:0)
[22:59:39.033] Timer tick, asking Buildbucket for the build status
[22:59:39.119] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:39.119] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:17:0) after 1m0s
[23:00:39.151] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:17:0)
[23:00:39.151] Timer tick, asking Buildbucket for the build status
[23:00:39.344] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:39.344] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:18:0) after 1m0s
[23:01:39.383] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:18:0)
[23:01:39.383] Timer tick, asking Buildbucket for the build status
[23:01:39.549] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:39.549] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:19:0) after 1m0s
[23:02:39.589] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:19:0)
[23:02:39.589] Timer tick, asking Buildbucket for the build status
[23:02:39.704] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:39.704] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:20:0) after 1m0s
[23:03:39.722] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:20:0)
[23:03:39.722] Timer tick, asking Buildbucket for the build status
[23:03:40.146] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:40.146] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:21:0) after 1m0s
[23:04:40.177] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:21:0)
[23:04:40.177] Timer tick, asking Buildbucket for the build status
[23:04:40.688] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:40.688] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:22:0) after 1m0s
[23:05:40.744] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:22:0)
[23:05:40.744] Timer tick, asking Buildbucket for the build status
[23:05:41.421] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:41.421] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:23:0) after 1m0s
[23:06:41.440] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:23:0)
[23:06:41.440] Timer tick, asking Buildbucket for the build status
[23:06:41.549] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:41.549] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:24:0) after 1m0s
[23:07:41.575] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:24:0)
[23:07:41.575] Timer tick, asking Buildbucket for the build status
[23:07:41.747] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:41.747] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:25:0) after 1m0s
[23:08:41.775] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:25:0)
[23:08:41.775] Timer tick, asking Buildbucket for the build status
[23:08:41.925] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:41.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:26:0) after 1m0s
[23:09:42.245] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:26:0)
[23:09:42.246] Timer tick, asking Buildbucket for the build status
[23:09:42.327] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:42.327] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:27:0) after 1m0s
[23:10:42.387] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:27:0)
[23:10:42.387] Timer tick, asking Buildbucket for the build status
[23:10:42.485] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:42.485] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:28:0) after 1m0s
[23:11:42.595] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:28:0)
[23:11:42.595] Timer tick, asking Buildbucket for the build status
[23:11:42.667] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:42.667] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:29:0) after 1m0s
[23:12:42.919] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:29:0)
[23:12:42.919] Timer tick, asking Buildbucket for the build status
[23:12:43.015] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:43.015] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:30:0) after 1m0s
[23:13:43.057] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:30:0)
[23:13:43.057] Timer tick, asking Buildbucket for the build status
[23:13:43.124] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:43.124] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:31:0) after 1m0s
[23:14:43.231] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:31:0)
[23:14:43.231] Timer tick, asking Buildbucket for the build status
[23:14:43.331] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:43.331] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:32:0) after 1m0s
[23:15:43.467] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:32:0)
[23:15:43.467] Timer tick, asking Buildbucket for the build status
[23:15:43.641] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:43.641] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:33:0) after 1m0s
[23:16:43.687] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:33:0)
[23:16:43.687] Timer tick, asking Buildbucket for the build status
[23:16:43.785] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:43.785] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:34:0) after 1m0s
[23:17:43.814] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:34:0)
[23:17:43.814] Timer tick, asking Buildbucket for the build status
[23:17:44.197] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:44.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:35:0) after 1m0s
[23:18:44.249] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:35:0)
[23:18:44.249] Timer tick, asking Buildbucket for the build status
[23:18:44.354] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:44.354] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:36:0) after 1m0s
[23:19:44.375] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:36:0)
[23:19:44.375] Timer tick, asking Buildbucket for the build status
[23:19:44.464] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:44.464] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:37:0) after 1m0s
[23:20:44.495] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:37:0)
[23:20:44.495] Timer tick, asking Buildbucket for the build status
[23:20:44.589] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:44.589] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:38:0) after 1m0s
[23:21:44.634] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:38:0)
[23:21:44.634] Timer tick, asking Buildbucket for the build status
[23:21:44.733] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:44.733] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:39:0) after 1m0s
[23:22:44.754] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:39:0)
[23:22:44.754] Timer tick, asking Buildbucket for the build status
[23:22:44.825] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:44.825] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:40:0) after 1m0s
[23:23:44.916] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:40:0)
[23:23:44.916] Timer tick, asking Buildbucket for the build status
[23:23:45.004] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:45.004] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:41:0) after 1m0s
[23:24:45.759] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:41:0)
[23:24:45.759] Timer tick, asking Buildbucket for the build status
[23:24:45.821] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:45.821] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:42:0) after 1m0s
[23:25:45.895] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:42:0)
[23:25:45.895] Timer tick, asking Buildbucket for the build status
[23:25:46.167] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:46.167] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:43:0) after 1m0s
[23:26:46.215] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:43:0)
[23:26:46.215] Timer tick, asking Buildbucket for the build status
[23:26:46.297] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:46.297] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:44:0) after 1m0s
[23:27:46.311] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:44:0)
[23:27:46.311] Timer tick, asking Buildbucket for the build status
[23:27:46.896] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:46.896] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:45:0) after 1m0s
[23:28:46.945] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:45:0)
[23:28:46.946] Timer tick, asking Buildbucket for the build status
[23:28:47.054] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:47.054] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:46:0) after 1m0s
[23:29:47.075] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:46:0)
[23:29:47.075] Timer tick, asking Buildbucket for the build status
[23:29:47.140] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:47.140] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:47:0) after 1m0s
[23:30:47.162] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:47:0)
[23:30:47.162] Timer tick, asking Buildbucket for the build status
[23:30:47.267] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:47.267] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:48:0) after 1m0s
[23:31:47.356] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:48:0)
[23:31:47.356] Timer tick, asking Buildbucket for the build status
[23:31:47.573] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:47.573] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:49:0) after 1m0s
[23:32:47.615] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:49:0)
[23:32:47.615] Timer tick, asking Buildbucket for the build status
[23:32:49.036] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:49.036] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:50:0) after 1m0s
[23:33:49.124] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:50:0)
[23:33:49.124] Timer tick, asking Buildbucket for the build status
[23:33:49.272] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:49.272] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:51:0) after 1m0s
[23:34:49.422] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:51:0)
[23:34:49.422] Timer tick, asking Buildbucket for the build status
[23:34:49.550] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:49.550] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:52:0) after 1m0s
[23:35:49.582] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:52:0)
[23:35:49.582] Timer tick, asking Buildbucket for the build status
[23:35:49.686] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:49.686] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:53:0) after 1m0s
[23:36:49.801] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:53:0)
[23:36:49.801] Timer tick, asking Buildbucket for the build status
[23:36:49.884] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:49.884] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:54:0) after 1m0s
[23:37:49.948] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:54:0)
[23:37:49.948] Timer tick, asking Buildbucket for the build status
[23:37:50.015] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:50.015] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:55:0) after 1m0s
[23:38:50.118] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:55:0)
[23:38:50.118] Timer tick, asking Buildbucket for the build status
[23:38:50.273] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:50.273] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:56:0) after 1m0s
[23:39:50.318] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:56:0)
[23:39:50.318] Timer tick, asking Buildbucket for the build status
[23:39:50.385] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:50.385] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:57:0) after 1m0s
[23:40:50.461] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:57:0)
[23:40:50.461] Timer tick, asking Buildbucket for the build status
[23:40:50.549] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:50.549] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:58:0) after 1m0s
[23:41:50.574] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:58:0)
[23:41:50.574] Timer tick, asking Buildbucket for the build status
[23:41:50.727] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:50.727] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:59:0) after 1m0s
[23:42:50.725] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:59:0)
[23:42:50.725] Timer tick, asking Buildbucket for the build status
[23:42:50.797] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:50.797] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:60:0) after 1m0s
[23:43:50.810] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:60:0)
[23:43:50.810] Timer tick, asking Buildbucket for the build status
[23:43:50.886] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:50.886] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:61:0) after 1m0s
[23:44:50.957] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:61:0)
[23:44:50.957] Timer tick, asking Buildbucket for the build status
[23:44:51.027] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:51.027] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:62:0) after 1m0s
[23:45:51.177] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:62:0)
[23:45:51.177] Timer tick, asking Buildbucket for the build status
[23:45:51.265] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:51.265] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:63:0) after 1m0s
[23:46:51.375] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:63:0)
[23:46:51.375] Timer tick, asking Buildbucket for the build status
[23:46:51.476] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:51.476] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:64:0) after 1m0s
[23:47:51.536] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:64:0)
[23:47:51.536] Timer tick, asking Buildbucket for the build status
[23:47:52.001] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:52.001] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:65:0) after 1m0s
[23:48:52.111] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:65:0)
[23:48:52.111] Timer tick, asking Buildbucket for the build status
[23:48:52.423] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:52.423] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:66:0) after 1m0s
[23:49:52.478] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:66:0)
[23:49:52.478] Timer tick, asking Buildbucket for the build status
[23:49:52.696] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:52.696] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:67:0) after 1m0s
[23:50:52.765] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:67:0)
[23:50:52.765] Timer tick, asking Buildbucket for the build status
[23:50:52.924] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:52.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:68:0) after 1m0s
[23:51:52.958] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:68:0)
[23:51:52.958] Timer tick, asking Buildbucket for the build status
[23:51:53.041] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:53.041] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:69:0) after 1m0s
[23:52:53.127] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:69:0)
[23:52:53.127] Timer tick, asking Buildbucket for the build status
[23:52:53.519] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:53.519] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:70:0) after 1m0s
[23:53:53.619] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:70:0)
[23:53:53.619] Timer tick, asking Buildbucket for the build status
[23:53:53.755] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:53.755] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:71:0) after 1m0s
[23:54:53.927] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:71:0)
[23:54:53.927] Timer tick, asking Buildbucket for the build status
[23:54:54.294] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:54.294] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:72:0) after 1m0s
[23:55:54.290] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:72:0)
[23:55:54.290] Timer tick, asking Buildbucket for the build status
[23:55:54.418] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:54.418] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:73:0) after 1m0s
[23:56:54.427] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:73:0)
[23:56:54.427] Timer tick, asking Buildbucket for the build status
[23:56:54.502] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:54.502] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:74:0) after 1m0s
[23:57:54.510] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:74:0)
[23:57:54.510] Timer tick, asking Buildbucket for the build status
[23:57:54.605] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:54.605] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:75:0) after 1m0s
[23:58:54.780] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:75:0)
[23:58:54.780] Timer tick, asking Buildbucket for the build status
[23:58:54.861] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:54.861] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:76:0) after 1m0s
[23:59:54.985] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:76:0)
[23:59:54.985] Timer tick, asking Buildbucket for the build status
[23:59:55.109] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:55.109] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:77:0) after 1m0s
[00:00:55.205] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:77:0)
[00:00:55.205] Timer tick, asking Buildbucket for the build status
[00:00:55.534] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:55.534] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:78:0) after 1m0s
[00:01:55.780] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:78:0)
[00:01:55.780] Timer tick, asking Buildbucket for the build status
[00:01:55.868] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:55.868] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:79:0) after 1m0s
[00:02:55.919] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:79:0)
[00:02:55.919] Timer tick, asking Buildbucket for the build status
[00:02:56.015] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:56.015] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:80:0) after 1m0s
[00:03:56.284] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:80:0)
[00:03:56.284] Timer tick, asking Buildbucket for the build status
[00:03:56.455] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:56.455] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:81:0) after 1m0s
[00:04:56.520] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:81:0)
[00:04:56.520] Timer tick, asking Buildbucket for the build status
[00:04:56.628] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:56.628] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:82:0) after 1m0s
[00:05:56.871] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:82:0)
[00:05:56.871] Timer tick, asking Buildbucket for the build status
[00:05:57.534] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:57.534] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:83:0) after 1m0s
[00:06:57.653] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:83:0)
[00:06:57.653] Timer tick, asking Buildbucket for the build status
[00:06:58.093] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:58.093] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:84:0) after 1m0s
[00:07:58.335] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:84:0)
[00:07:58.335] Timer tick, asking Buildbucket for the build status
[00:07:58.410] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:58.411] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:85:0) after 1m0s
[00:08:58.622] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:85:0)
[00:08:58.622] Timer tick, asking Buildbucket for the build status
[00:08:58.728] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:58.728] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:86:0) after 1m0s
[00:09:58.903] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:86:0)
[00:09:58.903] Timer tick, asking Buildbucket for the build status
[00:09:58.997] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:58.997] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:87:0) after 1m0s
[00:10:58.999] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:87:0)
[00:10:58.999] Timer tick, asking Buildbucket for the build status
[00:10:59.123] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:59.123] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:88:0) after 1m0s
[00:11:59.247] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:88:0)
[00:11:59.247] Timer tick, asking Buildbucket for the build status
[00:11:59.425] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:59.425] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:89:0) after 1m0s
[00:12:59.484] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:89:0)
[00:12:59.484] Timer tick, asking Buildbucket for the build status
[00:12:59.579] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:59.579] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:90:0) after 1m0s
[00:13:59.692] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:90:0)
[00:13:59.692] Timer tick, asking Buildbucket for the build status
[00:13:59.770] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:59.770] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:91:0) after 1m0s
[00:14:59.792] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:91:0)
[00:14:59.792] Timer tick, asking Buildbucket for the build status
[00:14:59.927] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:59.927] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:92:0) after 1m0s
[00:15:59.947] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:92:0)
[00:15:59.947] Timer tick, asking Buildbucket for the build status
[00:16:00.112] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:00.112] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:93:0) after 1m0s
[00:17:00.475] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:93:0)
[00:17:00.475] Timer tick, asking Buildbucket for the build status
[00:17:00.781] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:00.781] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:94:0) after 1m0s
[00:18:01.155] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:94:0)
[00:18:01.155] Timer tick, asking Buildbucket for the build status
[00:18:01.284] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:01.284] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:95:0) after 1m0s
[00:19:01.337] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:95:0)
[00:19:01.337] Timer tick, asking Buildbucket for the build status
[00:19:01.891] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:01.891] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:96:0) after 1m0s
[00:20:01.973] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:96:0)
[00:20:01.973] Timer tick, asking Buildbucket for the build status
[00:20:02.145] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:02.145] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:97:0) after 1m0s
[00:21:02.152] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:97:0)
[00:21:02.152] Timer tick, asking Buildbucket for the build status
[00:21:02.441] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:02.441] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:98:0) after 1m0s
[00:22:02.439] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:98:0)
[00:22:02.439] Timer tick, asking Buildbucket for the build status
[00:22:02.749] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:02.749] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:99:0) after 1m0s
[00:23:02.778] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:99:0)
[00:23:02.778] Timer tick, asking Buildbucket for the build status
[00:23:02.897] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:02.898] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:100:0) after 1m0s
[00:24:02.908] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:100:0)
[00:24:02.908] Timer tick, asking Buildbucket for the build status
[00:24:03.017] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:03.017] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:101:0) after 1m0s
[00:25:03.063] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:101:0)
[00:25:03.063] Timer tick, asking Buildbucket for the build status
[00:25:03.606] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:03.606] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:102:0) after 1m0s
[00:26:03.651] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:102:0)
[00:26:03.651] Timer tick, asking Buildbucket for the build status
[00:26:03.786] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:03.786] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:103:0) after 1m0s
[00:27:03.840] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:103:0)
[00:27:03.840] Timer tick, asking Buildbucket for the build status
[00:27:03.928] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:03.928] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:104:0) after 1m0s
[00:28:03.956] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:104:0)
[00:28:03.956] Timer tick, asking Buildbucket for the build status
[00:28:04.288] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:04.288] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:105:0) after 1m0s
[00:29:04.355] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:105:0)
[00:29:04.355] Timer tick, asking Buildbucket for the build status
[00:29:04.446] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:04.447] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:106:0) after 1m0s
[00:30:04.482] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:106:0)
[00:30:04.482] Timer tick, asking Buildbucket for the build status
[00:30:04.581] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:04.581] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:107:0) after 1m0s
[00:31:04.682] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:107:0)
[00:31:04.682] Timer tick, asking Buildbucket for the build status
[00:31:05.022] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:05.022] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:108:0) after 1m0s
[00:32:05.081] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:108:0)
[00:32:05.081] Timer tick, asking Buildbucket for the build status
[00:32:05.215] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:05.215] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:109:0) after 1m0s
[00:33:05.235] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:109:0)
[00:33:05.235] Timer tick, asking Buildbucket for the build status
[00:33:05.314] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:05.314] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:110:0) after 1m0s
[00:34:05.349] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:110:0)
[00:34:05.349] Timer tick, asking Buildbucket for the build status
[00:34:05.443] Build 8854918856368470192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:05.443] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020348918501999408:111:0) after 1m0s
[00:34:49.152] Received PubSub notification, asking Buildbucket for the build status
[00:34:49.243] Build 8854918856368470192: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[00:34:49.243] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1613694887321800",
"created_by": "project:chromium",
"created_ts": "1613688335027132",
"id": "8854918856368470192",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1588199ead5a8b8778c8365c5eeef0847c58113d\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 500.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"1588199ead5a8b8778c8365c5eeef0847c58113d\", \"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-1-2dz0\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17416, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-2\", \"got_angle_revision\": \"fd132e4e7a9c84333b4fb4730f79367fe75f53e7\", \"got_dawn_revision\": \"0b17eb8e19c886680b12b73ba28de8d30adccda9\", \"got_nacl_revision\": \"8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1\", \"got_revision\": \"1588199ead5a8b8778c8365c5eeef0847c58113d\", \"got_revision_cp\": \"refs/heads/master@{#855485}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"dc5bb2195a9ab47c0ffab51364c2e18cee90a18c\", \"got_v8_revision\": \"30164c51c7d9b98e9387e7343a6bc3413dc2f053\", \"got_v8_revision_cp\": \"refs/heads/9.0.216@{#1}\", \"got_webrtc_revision\": \"735e33fae0a06ae05a9c0be6f5d8b06a6ad820a4\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33297}\", \"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\": \"1588199ead5a8b8778c8365c5eeef0847c58113d\", \"swarm_hashes_refs/heads/master(at){#855485}_without_patch\": {\"absl_hardening_tests\": \"d6f364d6826162d9115a702c59bcbc0138430dae\", \"accessibility_unittests\": \"8788ad211caceacc1fb7879a344668c2251225ff\", \"angle_unittests\": \"1ff18e21ca9b740aa6cc9a56c2bd623cdaa269f1\", \"app_shell_unittests\": \"a22502db0db6ee3340c3d6531da2832272e0c3ff\", \"aura_unittests\": \"03d639be1504b9480207d595ead9ba01ba7ca845\", \"base_unittests\": \"121c5b4ccf802e631a5e59aee45667a5a406ae91\", \"base_util_unittests\": \"00d3460376068eed2ef7cad6b3fa5f9c40740b6a\", \"blink_common_unittests\": \"0ce28e0c99b904b15f959afda1432cf6758f41e1\", \"blink_fuzzer_unittests\": \"7f5e54e5653f907045305e390a626fbb28c523ef\", \"blink_heap_unittests\": \"d377c5aca6aa7a197f65f73027218b3d455eb965\", \"blink_platform_unittests\": \"aeb39175dfc07b0cf46376559482e850812a5600\", \"blink_unittests\": \"800f19cbc23470568fa11d42758197449112184f\", \"boringssl_crypto_tests\": \"9ff613160460f57aa873a1d166a4f737de6eac28\", \"boringssl_ssl_tests\": \"af827da2f73f9e2b13050b11dbe3124b53eb9ac5\", \"browser_switcher_bho_unittests\": \"f78e3e58787643a5a5b1286acf4cfa1e3636e63c\", \"browser_tests\": \"aa220bf91de572f6dbb004cf6b144d654c5158ee\", \"capture_unittests\": \"21dff5fdb590b34aa8c0a56cf01e35df0d784fb1\", \"cast_unittests\": \"b55854ed54992a036e6e61199b5728863660731d\", \"cc_unittests\": \"52d4b5ab05abc16d5f0f004987a056f9e78ba3eb\", \"chrome_app_unittests\": \"06d25fb26a6644af38fdaf0fca155eefd8f1b222\", \"chrome_cleaner_unittests\": \"a07cc2fa03ba6672d02ec6cea73590e54afbb46b\", \"chrome_elf_unittests\": \"69db3701d94966a83850aa9d5969d6af7deaff6c\", \"chromedriver_unittests\": \"935d12380c67f0756b347789d6e6f994a89ab6ba\", \"components_browsertests\": \"1adc812e40189f59f2d228c09d5faebafce39fc0\", \"components_unittests\": \"b85400d92e3be04f01131137b11b127f245f6510\", \"compositor_unittests\": \"ddf0b97ac792ba7c4566abb0264c39da401c68da\", \"content_browsertests\": \"4f03fe1d02afc1c7b8c41ae4144a628698808b08\", \"content_unittests\": \"8156548d95230188a05c21f5e649570f9ce3f275\", \"courgette_unittests\": \"1ff34a8e374213fe30b8951bfda45db80641259a\", \"crashpad_tests\": \"d0aeaa8a201dd2d114ca66bd83f4a0e0daad9e92\", \"cronet_tests\": \"25bee06a7abc49b6eaf2702f20a9f93e2bbfa6b7\", \"cronet_unittests\": \"be7eac0687bbe82678a987bee45c609c1e38ee7a\", \"crypto_unittests\": \"f2d25b0b7f0401bde9c2f086cd3fa16d14e76c70\", \"delayloads_unittests\": \"d1097bcf70461661a7f5f52f54b91c17907ac2d9\", \"device_unittests\": \"2d27e71279fddcf860fe54113e175419ef42b7a5\", \"display_unittests\": \"8120bef8897a582f37971ce8f09d4535ea0d44c5\", \"elevation_service_unittests\": \"cc495a07651070eec5579f75dfb3d19b8bcc3676\", \"events_unittests\": \"477f4c7c3cf34e4307be5d347e85b0bc19a63df9\", \"extensions_browsertests\": \"3ba02786031b21140a4fc261b04309959ceff9db\", \"extensions_unittests\": \"eb02c7319cb3d6bfb2a360ce821b97114ab0d4fd\", \"filesystem_service_unittests\": \"2c104ef826575d06839584806a51fc75a996ef7b\", \"gcm_unit_tests\": \"ea4785d5ade36722fad002c52c59f5fb91a0c9a8\", \"gcp_unittests\": \"9ac90d5c49fa609f61fb4bb93dd32ad2b1d4a598\", \"gfx_unittests\": \"2cd60c5efa75dfed31f0b154d46f9573f7562670\", \"gin_unittests\": \"4c4aaedac93a089329f2d4bf6e39c3639e3453c9\", \"google_apis_unittests\": \"63a2f16a3fb9f5819236ca1a13bb7726381ae508\", \"gpu_unittests\": \"3707fbea1ad5669f79cd383be6a5375d0cc4a5b2\", \"gwp_asan_unittests\": \"87fb407461e531b1d1f933b3088e60a4b606672f\", \"headless_browsertests\": \"a5c88d97847dad44f1ff2de1d1063fdd1f8a2132\", \"headless_unittests\": \"81643aeb1e5f150ff8bc253877cd59c8a177941d\", \"install_static_unittests\": \"940861d0f56f6c9afc87108df798c8ca65111981\", \"installer_util_unittests\": \"cd700d99bd82687b1f4ff508586723d87b4bbbf3\", \"interactive_ui_tests\": \"87172f574dbfbb74ce75e0598ab82b55ff2b1fe6\", \"ipc_tests\": \"90789c60f95af74e555d451f74e0ace7d394c9ae\", \"jingle_unittests\": \"88ff030a6b20c3b79339f125dd067e0308d05e25\", \"latency_unittests\": \"9296aa8da3f2ca10251e5805b1dd26ed7408ac5f\", \"libjingle_xmpp_unittests\": \"5184de128717006d73f01571a3a6096b9e7681de\", \"liburlpattern_unittests\": \"61e0ae013d24f14edccd0154635f3b9ae49132f0\", \"media_blink_unittests\": \"0cd3a2c9becc54abeafe1b89d5bd6d40129d18fa\", \"media_unittests\": \"52ad71a8790d2d29dd5fa6e7e978357cec64859c\", \"message_center_unittests\": \"23dfa9f3ac1a6a4edb6b900899b1f514442a4186\", \"midi_unittests\": \"6b68b57bd5063ce15f628ac2835d9f38bea382ef\", \"mojo_core_unittests\": \"9503246f1a8f7f8b3abb2d550ea487fe4de4bcde\", \"mojo_unittests\": \"f9a3a8a624e326fdc33c0437f4a0b5af270267c0\", \"nacl_loader_unittests\": \"cb67b808fadb9d937946b5fb8f40e33c1e327769\", \"native_theme_unittests\": \"3c45b2207faaf240cd76f6f982265d33462d61a8\", \"net_unittests\": \"246488622b60ce6f9ba155fcbe4c21530af1046d\", \"notification_helper_unittests\": \"3eb4f12de0afc01183a4b080182de1550ae1264d\", \"pdf_unittests\": \"36aa068222b75da1a21a0299f8f806fc78a75f98\", \"perfetto_unittests\": \"e0f59c51bb827708a09790762b2f90f724917728\", \"ppapi_unittests\": \"0482d1f1e498ebfbde0c040fe6d6582cf6adb7bb\", \"printing_unittests\": \"40b7b8131bd0ed89fa5770a17ed6c1785fca1c61\", \"remoting_unittests\": \"94cc060c71b9cb8f0c015d9ffd8315743533dd81\", \"sbox_integration_tests\": \"7a6139b6fefa966e946f68d63a7ad123e0085054\", \"sbox_unittests\": \"7cb3a843f0f8d53c5a8dedb541e00f9a1d94e34f\", \"sbox_validation_tests\": \"dd9e4854d7dde2e44d34eb0a7db0373e93358f54\", \"service_manager_unittests\": \"2159d6920f13864f6ce7cd84d1b96950a2114b63\", \"services_unittests\": \"1f3017ad69dc5d4e32559be8cebd6cd88c0d4e5e\", \"setup_unittests\": \"d1f8fb23f20aeff99f1625c267c0d7104832f5ad\", \"shell_dialogs_unittests\": \"632cc849bc8ed2be75a2515650495a3f5e44c309\", \"skia_unittests\": \"12112a896a37b1fd02b4af9f44b4f2e2154317a1\", \"snapshot_unittests\": \"497cc10bab57d81a7340d4bc55dddfd9b2411184\", \"sql_unittests\": \"6eb19d67f699b308c68d1e6e3f1beaffaba89e0d\", \"storage_unittests\": \"720224bbfd08d7291928c3ecc36df47760992c36\", \"sync_integration_tests\": \"d1ffceb63889b1bdb4c533bea475bd05ed9b14b5\", \"traffic_annotation_auditor_unittests\": \"406835dd386b9bcc3613b6329e8b334643396946\", \"ui_base_unittests\": \"1389310b27f70006f9b4b11d7f85d58d311835e9\", \"ui_touch_selection_unittests\": \"f03e09c3a323bab34b2a4c2df91eb5d4999507b6\", \"unit_tests\": \"c67c6fe7b6a722135a4f043fbfd4334cad6eb170\", \"updater_tests\": \"a880e88ab772e8d3c1d0c18ba848a37664fd5f58\", \"url_unittests\": \"2084a609522081dc1aa7eb6c62f0bfae5bcef66e\", \"views_unittests\": \"eb1908f1c22fbd572f3e10dc9f66675571da1af6\", \"viz_unittests\": \"c9acafc0d8b0a9ee12f6012541a61f2831517b95\", \"vr_common_unittests\": \"0708292f1f36bea5b3e733c33cdbfb36792e258a\", \"vr_pixeltests\": \"e304a5e7f5e75e93d131fe3da184827f415abb65\", \"weblayer_browsertests\": \"65a9580aaf727a7d52755deeda6aa59ca1999b8f\", \"weblayer_unittests\": \"d9b02fd9b44b4f64d483d71f8a5f0e95d4ced685\", \"wm_unittests\": \"4c6fe26f3b59d7b91f6fc29fe444f0e9c10e230e\", \"wtf_unittests\": \"3fbfdb5cab527d5e3ceacce2ea4be96d74733ac4\", \"zlib_unittests\": \"e5d605ce5a34effe5d726b40ace3447fde3ba099\", \"zucchini_unittests\": \"c7dcf2bcb6607dfd7d02113863fff308355a2a3c\"}}, \"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-1-2dz0\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5700-b36f18b\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1613688501628148",
"status": "COMPLETED",
"status_changed_ts": "1613694887839062",
"tags": [
"build_address:luci.chromium.ci/win-asan/17416",
"builder:win-asan",
"buildset:commit/git/1588199ead5a8b8778c8365c5eeef0847c58113d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1588199ead5a8b8778c8365c5eeef0847c58113d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020348918501999408",
"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/8854918856368470192/+/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:51d023760d727c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1613694887844922",
"url": "https://ci.chromium.org/b/8854918856368470192",
"utcnow_ts": "1613694889235394"
}
}
[00:34:49.243] Invocation finished in 1h49m15.633309295s with status SUCCEEDED