[16:50:58.279] New invocation is queued and will start shortly
[16:50:59.381] Starting the invocation (attempt 1)
[16:50:59.418] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:50:59.419] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:50:59.419] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022183166646652896",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"076ee09508aadd01c7c67e424660a552226f4447\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9022183166646652896",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/076ee09508aadd01c7c67e424660a552226f4447",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/076ee09508aadd01c7c67e424660a552226f4447",
"gitiles_ref:refs/heads/master"
]
}
[16:51:00.203] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611939059557706",
"id": "8856753104644621216",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"076ee09508aadd01c7c67e424660a552226f4447\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611939059963005",
"tags": [
"build_address:luci.chromium.ci/win-asan/17071",
"builder:win-asan",
"buildset:commit/git/076ee09508aadd01c7c67e424660a552226f4447",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/076ee09508aadd01c7c67e424660a552226f4447",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022183166646652896",
"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/8856753104644621216/+/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": "1611939059963257",
"url": "https://ci.chromium.org/b/8856753104644621216",
"utcnow_ts": "1611939060083908"
}
}
[16:51:00.203] Task URL: https://ci.chromium.org/b/8856753104644621216
[16:51:00.203] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:2:0) after 1m0s
[16:52:00.561] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:2:0)
[16:52:00.561] Timer tick, asking Buildbucket for the build status
[16:52:00.678] Build 8856753104644621216: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:52:00.678] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:3:0) after 1m0s
[16:53:00.739] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:3:0)
[16:53:00.739] Timer tick, asking Buildbucket for the build status
[16:53:00.922] Build 8856753104644621216: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:53:00.922] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:4:0) after 1m0s
[16:54:01.386] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:4:0)
[16:54:01.386] Timer tick, asking Buildbucket for the build status
[16:54:01.470] Build 8856753104644621216: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:54:01.470] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:5:0) after 1m0s
[16:55:01.572] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:5:0)
[16:55:01.572] Timer tick, asking Buildbucket for the build status
[16:55:02.264] Build 8856753104644621216: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:55:02.264] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:6:0) after 1m0s
[16:56:02.406] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:6:0)
[16:56:02.406] Timer tick, asking Buildbucket for the build status
[16:56:02.617] Build 8856753104644621216: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:56:02.617] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:7:0) after 1m0s
[16:57:02.644] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:7:0)
[16:57:02.644] Timer tick, asking Buildbucket for the build status
[16:57:02.712] Build 8856753104644621216: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:57:02.712] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:8:0) after 1m0s
[16:58:02.769] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:8:0)
[16:58:02.769] Timer tick, asking Buildbucket for the build status
[16:58:03.142] Build 8856753104644621216: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:58:03.142] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:9:0) after 1m0s
[16:59:03.201] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:9:0)
[16:59:03.201] Timer tick, asking Buildbucket for the build status
[16:59:03.334] Build 8856753104644621216: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:59:03.334] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:10:0) after 1m0s
[16:59:10.925] Received PubSub notification, asking Buildbucket for the build status
[16:59:10.985] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:03.428] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:10:0)
[17:00:03.428] Timer tick, asking Buildbucket for the build status
[17:00:03.705] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:03.705] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:12:0) after 1m0s
[17:01:03.793] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:12:0)
[17:01:03.793] Timer tick, asking Buildbucket for the build status
[17:01:03.939] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:03.939] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:13:0) after 1m0s
[17:02:04.135] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:13:0)
[17:02:04.135] Timer tick, asking Buildbucket for the build status
[17:02:04.239] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:04.239] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:14:0) after 1m0s
[17:03:04.313] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:14:0)
[17:03:04.313] Timer tick, asking Buildbucket for the build status
[17:03:04.397] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:04.397] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:15:0) after 1m0s
[17:04:04.429] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:15:0)
[17:04:04.429] Timer tick, asking Buildbucket for the build status
[17:04:04.500] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:04.500] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:16:0) after 1m0s
[17:05:04.607] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:16:0)
[17:05:04.607] Timer tick, asking Buildbucket for the build status
[17:05:04.672] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:04.672] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:17:0) after 1m0s
[17:06:04.739] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:17:0)
[17:06:04.739] Timer tick, asking Buildbucket for the build status
[17:06:05.073] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:05.073] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:18:0) after 1m0s
[17:07:05.140] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:18:0)
[17:07:05.140] Timer tick, asking Buildbucket for the build status
[17:07:05.212] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:05.212] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:19:0) after 1m0s
[17:08:05.283] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:19:0)
[17:08:05.283] Timer tick, asking Buildbucket for the build status
[17:08:05.376] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:05.376] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:20:0) after 1m0s
[17:09:05.449] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:20:0)
[17:09:05.449] Timer tick, asking Buildbucket for the build status
[17:09:05.530] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:05.530] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:21:0) after 1m0s
[17:10:05.634] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:21:0)
[17:10:05.634] Timer tick, asking Buildbucket for the build status
[17:10:05.909] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:05.909] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:22:0) after 1m0s
[17:11:06.079] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:22:0)
[17:11:06.079] Timer tick, asking Buildbucket for the build status
[17:11:06.937] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:06.937] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:23:0) after 1m0s
[17:12:07.063] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:23:0)
[17:12:07.063] Timer tick, asking Buildbucket for the build status
[17:12:07.188] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:07.188] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:24:0) after 1m0s
[17:13:07.247] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:24:0)
[17:13:07.247] Timer tick, asking Buildbucket for the build status
[17:13:07.554] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:07.554] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:25:0) after 1m0s
[17:14:07.662] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:25:0)
[17:14:07.662] Timer tick, asking Buildbucket for the build status
[17:14:07.722] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:07.722] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:26:0) after 1m0s
[17:15:07.792] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:26:0)
[17:15:07.792] Timer tick, asking Buildbucket for the build status
[17:15:08.042] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:08.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:27:0) after 1m0s
[17:16:08.143] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:27:0)
[17:16:08.143] Timer tick, asking Buildbucket for the build status
[17:16:08.288] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:08.288] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:28:0) after 1m0s
[17:17:08.273] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:28:0)
[17:17:08.273] Timer tick, asking Buildbucket for the build status
[17:17:08.405] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:08.405] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:29:0) after 1m0s
[17:18:08.423] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:29:0)
[17:18:08.423] Timer tick, asking Buildbucket for the build status
[17:18:08.713] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:08.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:30:0) after 1m0s
[17:19:08.766] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:30:0)
[17:19:08.766] Timer tick, asking Buildbucket for the build status
[17:19:08.841] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:08.841] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:31:0) after 1m0s
[17:20:08.985] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:31:0)
[17:20:08.985] Timer tick, asking Buildbucket for the build status
[17:20:09.086] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:09.086] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:32:0) after 1m0s
[17:21:09.129] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:32:0)
[17:21:09.129] Timer tick, asking Buildbucket for the build status
[17:21:09.221] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:09.221] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:33:0) after 1m0s
[17:22:09.302] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:33:0)
[17:22:09.302] Timer tick, asking Buildbucket for the build status
[17:22:09.363] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:09.363] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:34:0) after 1m0s
[17:23:09.418] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:34:0)
[17:23:09.418] Timer tick, asking Buildbucket for the build status
[17:23:09.474] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:09.474] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:35:0) after 1m0s
[17:24:09.593] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:35:0)
[17:24:09.593] Timer tick, asking Buildbucket for the build status
[17:24:09.731] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:09.732] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:36:0) after 1m0s
[17:25:09.811] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:36:0)
[17:25:09.811] Timer tick, asking Buildbucket for the build status
[17:25:09.977] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:09.977] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:37:0) after 1m0s
[17:26:10.036] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:37:0)
[17:26:10.036] Timer tick, asking Buildbucket for the build status
[17:26:10.148] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:10.148] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:38:0) after 1m0s
[17:27:10.611] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:38:0)
[17:27:10.611] Timer tick, asking Buildbucket for the build status
[17:27:11.133] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:11.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:39:0) after 1m0s
[17:28:11.322] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:39:0)
[17:28:11.322] Timer tick, asking Buildbucket for the build status
[17:28:11.465] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:11.465] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:40:0) after 1m0s
[17:29:11.596] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:40:0)
[17:29:11.596] Timer tick, asking Buildbucket for the build status
[17:29:11.656] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:11.656] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:41:0) after 1m0s
[17:30:11.719] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:41:0)
[17:30:11.719] Timer tick, asking Buildbucket for the build status
[17:30:11.789] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:11.789] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:42:0) after 1m0s
[17:31:12.248] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:42:0)
[17:31:12.248] Timer tick, asking Buildbucket for the build status
[17:31:12.479] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:12.479] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:43:0) after 1m0s
[17:32:12.653] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:43:0)
[17:32:12.653] Timer tick, asking Buildbucket for the build status
[17:32:12.706] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:12.706] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:44:0) after 1m0s
[17:33:12.748] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:44:0)
[17:33:12.748] Timer tick, asking Buildbucket for the build status
[17:33:12.956] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:12.956] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:45:0) after 1m0s
[17:34:12.978] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:45:0)
[17:34:12.978] Timer tick, asking Buildbucket for the build status
[17:34:13.043] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:13.044] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:46:0) after 1m0s
[17:35:13.150] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:46:0)
[17:35:13.150] Timer tick, asking Buildbucket for the build status
[17:35:13.215] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:13.215] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:47:0) after 1m0s
[17:36:14.782] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:47:0)
[17:36:14.782] Timer tick, asking Buildbucket for the build status
[17:36:15.109] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:15.109] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:48:0) after 1m0s
[17:37:15.120] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:48:0)
[17:37:15.121] Timer tick, asking Buildbucket for the build status
[17:37:15.230] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:15.230] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:49:0) after 1m0s
[17:38:15.258] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:49:0)
[17:38:15.258] Timer tick, asking Buildbucket for the build status
[17:38:15.320] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:15.320] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:50:0) after 1m0s
[17:39:15.319] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:50:0)
[17:39:15.319] Timer tick, asking Buildbucket for the build status
[17:39:15.385] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:15.385] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:51:0) after 1m0s
[17:40:15.422] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:51:0)
[17:40:15.422] Timer tick, asking Buildbucket for the build status
[17:40:15.478] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:15.478] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:52:0) after 1m0s
[17:41:15.508] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:52:0)
[17:41:15.508] Timer tick, asking Buildbucket for the build status
[17:41:15.613] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:15.613] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:53:0) after 1m0s
[17:42:15.670] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:53:0)
[17:42:15.670] Timer tick, asking Buildbucket for the build status
[17:42:15.754] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:15.754] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:54:0) after 1m0s
[17:43:15.806] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:54:0)
[17:43:15.806] Timer tick, asking Buildbucket for the build status
[17:43:16.001] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:16.001] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:55:0) after 1m0s
[17:44:16.102] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:55:0)
[17:44:16.102] Timer tick, asking Buildbucket for the build status
[17:44:16.180] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:16.180] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:56:0) after 1m0s
[17:45:16.257] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:56:0)
[17:45:16.257] Timer tick, asking Buildbucket for the build status
[17:45:16.333] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:16.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:57:0) after 1m0s
[17:46:16.377] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:57:0)
[17:46:16.377] Timer tick, asking Buildbucket for the build status
[17:46:16.543] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:16.543] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:58:0) after 1m0s
[17:47:16.605] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:58:0)
[17:47:16.605] Timer tick, asking Buildbucket for the build status
[17:47:16.672] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:16.672] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:59:0) after 1m0s
[17:48:16.774] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:59:0)
[17:48:16.774] Timer tick, asking Buildbucket for the build status
[17:48:16.825] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:16.826] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:60:0) after 1m0s
[17:49:16.947] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:60:0)
[17:49:16.947] Timer tick, asking Buildbucket for the build status
[17:49:17.117] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:17.117] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:61:0) after 1m0s
[17:50:17.163] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:61:0)
[17:50:17.163] Timer tick, asking Buildbucket for the build status
[17:50:17.317] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:17.317] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:62:0) after 1m0s
[17:51:17.479] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:62:0)
[17:51:17.479] Timer tick, asking Buildbucket for the build status
[17:51:17.637] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:17.637] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:63:0) after 1m0s
[17:52:17.813] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:63:0)
[17:52:17.813] Timer tick, asking Buildbucket for the build status
[17:52:17.880] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:17.880] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:64:0) after 1m0s
[17:53:17.939] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:64:0)
[17:53:17.939] Timer tick, asking Buildbucket for the build status
[17:53:17.986] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:17.986] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:65:0) after 1m0s
[17:54:18.021] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:65:0)
[17:54:18.021] Timer tick, asking Buildbucket for the build status
[17:54:18.084] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:18.084] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:66:0) after 1m0s
[17:55:18.202] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:66:0)
[17:55:18.202] Timer tick, asking Buildbucket for the build status
[17:55:18.262] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:18.262] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:67:0) after 1m0s
[17:56:18.406] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:67:0)
[17:56:18.406] Timer tick, asking Buildbucket for the build status
[17:56:18.543] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:18.543] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:68:0) after 1m0s
[17:57:18.578] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:68:0)
[17:57:18.578] Timer tick, asking Buildbucket for the build status
[17:57:18.648] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:18.648] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:69:0) after 1m0s
[17:58:18.727] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:69:0)
[17:58:18.727] Timer tick, asking Buildbucket for the build status
[17:58:18.848] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:18.848] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:70:0) after 1m0s
[17:59:18.947] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:70:0)
[17:59:18.947] Timer tick, asking Buildbucket for the build status
[17:59:19.037] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:19.037] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:71:0) after 1m0s
[18:00:19.101] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:71:0)
[18:00:19.101] Timer tick, asking Buildbucket for the build status
[18:00:19.202] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:19.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:72:0) after 1m0s
[18:01:19.255] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:72:0)
[18:01:19.255] Timer tick, asking Buildbucket for the build status
[18:01:19.416] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:19.416] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:73:0) after 1m0s
[18:02:19.589] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:73:0)
[18:02:19.589] Timer tick, asking Buildbucket for the build status
[18:02:19.736] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:19.736] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:74:0) after 1m0s
[18:03:20.085] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:74:0)
[18:03:20.085] Timer tick, asking Buildbucket for the build status
[18:03:20.226] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:20.226] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:75:0) after 1m0s
[18:04:20.284] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:75:0)
[18:04:20.284] Timer tick, asking Buildbucket for the build status
[18:04:20.414] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:20.414] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:76:0) after 1m0s
[18:05:20.486] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:76:0)
[18:05:20.486] Timer tick, asking Buildbucket for the build status
[18:05:20.628] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:20.628] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:77:0) after 1m0s
[18:06:20.628] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:77:0)
[18:06:20.628] Timer tick, asking Buildbucket for the build status
[18:06:20.787] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:20.787] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:78:0) after 1m0s
[18:07:20.920] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:78:0)
[18:07:20.920] Timer tick, asking Buildbucket for the build status
[18:07:21.378] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:21.378] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:79:0) after 1m0s
[18:08:21.433] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:79:0)
[18:08:21.433] Timer tick, asking Buildbucket for the build status
[18:08:21.605] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:21.605] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:80:0) after 1m0s
[18:09:21.655] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:80:0)
[18:09:21.655] Timer tick, asking Buildbucket for the build status
[18:09:21.748] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:21.748] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:81:0) after 1m0s
[18:10:21.770] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:81:0)
[18:10:21.770] Timer tick, asking Buildbucket for the build status
[18:10:21.851] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:21.851] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:82:0) after 1m0s
[18:11:21.864] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:82:0)
[18:11:21.864] Timer tick, asking Buildbucket for the build status
[18:11:22.118] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:22.118] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:83:0) after 1m0s
[18:12:22.242] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:83:0)
[18:12:22.242] Timer tick, asking Buildbucket for the build status
[18:12:22.851] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:22.851] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:84:0) after 1m0s
[18:13:22.883] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:84:0)
[18:13:22.883] Timer tick, asking Buildbucket for the build status
[18:13:23.238] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:23.238] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:85:0) after 1m0s
[18:14:23.328] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:85:0)
[18:14:23.328] Timer tick, asking Buildbucket for the build status
[18:14:23.468] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:23.468] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:86:0) after 1m0s
[18:15:23.622] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:86:0)
[18:15:23.622] Timer tick, asking Buildbucket for the build status
[18:15:23.747] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:23.747] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:87:0) after 1m0s
[18:16:23.881] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:87:0)
[18:16:23.881] Timer tick, asking Buildbucket for the build status
[18:16:24.101] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:24.101] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:88:0) after 1m0s
[18:17:24.227] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:88:0)
[18:17:24.227] Timer tick, asking Buildbucket for the build status
[18:17:24.354] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:24.354] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:89:0) after 1m0s
[18:18:24.437] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:89:0)
[18:18:24.437] Timer tick, asking Buildbucket for the build status
[18:18:24.558] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:24.558] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:90:0) after 1m0s
[18:19:24.691] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:90:0)
[18:19:24.691] Timer tick, asking Buildbucket for the build status
[18:19:24.754] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:24.754] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:91:0) after 1m0s
[18:20:24.804] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:91:0)
[18:20:24.804] Timer tick, asking Buildbucket for the build status
[18:20:24.919] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:24.919] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:92:0) after 1m0s
[18:21:25.004] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:92:0)
[18:21:25.004] Timer tick, asking Buildbucket for the build status
[18:21:25.219] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:25.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:93:0) after 1m0s
[18:22:25.302] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:93:0)
[18:22:25.302] Timer tick, asking Buildbucket for the build status
[18:22:25.412] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:25.412] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:94:0) after 1m0s
[18:23:25.487] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:94:0)
[18:23:25.487] Timer tick, asking Buildbucket for the build status
[18:23:25.563] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:25.563] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:95:0) after 1m0s
[18:24:25.545] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:95:0)
[18:24:25.545] Timer tick, asking Buildbucket for the build status
[18:24:25.617] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:25.617] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:96:0) after 1m0s
[18:25:25.725] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:96:0)
[18:25:25.725] Timer tick, asking Buildbucket for the build status
[18:25:25.944] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:25.944] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:97:0) after 1m0s
[18:26:26.038] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:97:0)
[18:26:26.038] Timer tick, asking Buildbucket for the build status
[18:26:26.183] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:26.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:98:0) after 1m0s
[18:27:26.321] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:98:0)
[18:27:26.321] Timer tick, asking Buildbucket for the build status
[18:27:26.471] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:26.471] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:99:0) after 1m0s
[18:28:26.562] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:99:0)
[18:28:26.562] Timer tick, asking Buildbucket for the build status
[18:28:26.636] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:26.636] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:100:0) after 1m0s
[18:29:26.745] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:100:0)
[18:29:26.745] Timer tick, asking Buildbucket for the build status
[18:29:27.125] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:27.125] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:101:0) after 1m0s
[18:30:27.365] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:101:0)
[18:30:27.365] Timer tick, asking Buildbucket for the build status
[18:30:27.602] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:27.602] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:102:0) after 1m0s
[18:31:27.774] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:102:0)
[18:31:27.774] Timer tick, asking Buildbucket for the build status
[18:31:28.262] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:28.262] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:103:0) after 1m0s
[18:32:28.452] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:103:0)
[18:32:28.452] Timer tick, asking Buildbucket for the build status
[18:32:28.638] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:28.638] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:104:0) after 1m0s
[18:33:28.668] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:104:0)
[18:33:28.669] Timer tick, asking Buildbucket for the build status
[18:33:28.746] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:28.746] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:105:0) after 1m0s
[18:34:30.147] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:105:0)
[18:34:30.147] Timer tick, asking Buildbucket for the build status
[18:34:30.271] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:30.271] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:106:0) after 1m0s
[18:35:30.418] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:106:0)
[18:35:30.418] Timer tick, asking Buildbucket for the build status
[18:35:31.012] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:31.012] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:107:0) after 1m0s
[18:36:31.356] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:107:0)
[18:36:31.356] Timer tick, asking Buildbucket for the build status
[18:36:31.461] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:31.461] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:108:0) after 1m0s
[18:37:31.647] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:108:0)
[18:37:31.647] Timer tick, asking Buildbucket for the build status
[18:37:31.752] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:31.752] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:109:0) after 1m0s
[18:38:32.163] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:109:0)
[18:38:32.163] Timer tick, asking Buildbucket for the build status
[18:38:32.265] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:32.266] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:110:0) after 1m0s
[18:39:32.569] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:110:0)
[18:39:32.569] Timer tick, asking Buildbucket for the build status
[18:39:32.678] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:32.679] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:111:0) after 1m0s
[18:40:32.674] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:111:0)
[18:40:32.674] Timer tick, asking Buildbucket for the build status
[18:40:32.856] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:32.856] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:112:0) after 1m0s
[18:41:32.940] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:112:0)
[18:41:32.940] Timer tick, asking Buildbucket for the build status
[18:41:33.099] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:33.099] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:113:0) after 1m0s
[18:42:33.159] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:113:0)
[18:42:33.159] Timer tick, asking Buildbucket for the build status
[18:42:33.224] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:33.224] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:114:0) after 1m0s
[18:43:33.223] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:114:0)
[18:43:33.223] Timer tick, asking Buildbucket for the build status
[18:43:33.345] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:33.345] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:115:0) after 1m0s
[18:44:33.421] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:115:0)
[18:44:33.421] Timer tick, asking Buildbucket for the build status
[18:44:33.495] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:33.495] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:116:0) after 1m0s
[18:45:33.482] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:116:0)
[18:45:33.482] Timer tick, asking Buildbucket for the build status
[18:45:33.561] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:33.561] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:117:0) after 1m0s
[18:46:33.639] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:117:0)
[18:46:33.639] Timer tick, asking Buildbucket for the build status
[18:46:33.777] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:33.777] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:118:0) after 1m0s
[18:47:33.822] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:118:0)
[18:47:33.822] Timer tick, asking Buildbucket for the build status
[18:47:33.906] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:33.906] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:119:0) after 1m0s
[18:48:34.116] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:119:0)
[18:48:34.116] Timer tick, asking Buildbucket for the build status
[18:48:34.243] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:34.243] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:120:0) after 1m0s
[18:49:34.381] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:120:0)
[18:49:34.381] Timer tick, asking Buildbucket for the build status
[18:49:34.453] Build 8856753104644621216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:34.453] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022183166646652896:121:0) after 1m0s
[18:49:36.773] Received PubSub notification, asking Buildbucket for the build status
[18:49:36.842] Build 8856753104644621216: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[18:49:36.842] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611946176043930",
"created_by": "project:chromium",
"created_ts": "1611939059557706",
"id": "8856753104644621216",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"076ee09508aadd01c7c67e424660a552226f4447\"}}",
"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\": \"076ee09508aadd01c7c67e424660a552226f4447\", \"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-7-2fjn\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17071, \"clang_revision\": \"llvmorg-12-init-17251-g6de48655-1\", \"got_angle_revision\": \"a29ef6129501a14774387f23574d30459b70f9e6\", \"got_dawn_revision\": \"c1d3a66bd217395a501543b09c6d2c25e9d2e22e\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"076ee09508aadd01c7c67e424660a552226f4447\", \"got_revision_cp\": \"refs/heads/master@{#848559}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"3e9b79ff42de0f5547809354de89e24c7a439e39\", \"got_v8_revision\": \"89b433d7c3defd04809fba9deb6f8885160044a4\", \"got_v8_revision_cp\": \"refs/heads/9.0.100@{#1}\", \"got_webrtc_revision\": \"54b925cfc2cd7c2f8f83e8e484f281aa87b92dfc\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33090}\", \"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\": \"076ee09508aadd01c7c67e424660a552226f4447\", \"swarm_hashes_refs/heads/master(at){#848559}_without_patch\": {\"absl_hardening_tests\": \"3bc77be6e6b4a0eb7e3cd216775dce7ef7c6e821\", \"accessibility_unittests\": \"3ac34e8afaccb1349fdc6dbf35f5ef45410bb4dd\", \"angle_unittests\": \"9f68287e3a9de24ddf6e31fd2fa8c090cededbb9\", \"app_shell_unittests\": \"b5164795f554bf188b429f8e40ea2af6002402c9\", \"aura_unittests\": \"a6261a4210f603d94f64db2a2486807e4a74a9fa\", \"base_unittests\": \"b68f3540ad99fac994106b20fad664a205556618\", \"base_util_unittests\": \"35d9cb2ef694dd782f7bf14417f31dc32e2ef654\", \"blink_common_unittests\": \"302a9f1b0b9094bf4077075d0257d559ece2e3e9\", \"blink_fuzzer_unittests\": \"8dd913be715f3a623ab1e5dda951405a2630da2d\", \"blink_heap_unittests\": \"a1eef34351f6f93b68599941407ba405acb6c017\", \"blink_platform_unittests\": \"0ff35b36bfbed2a723d3683fd54b0a3f801e0932\", \"blink_unittests\": \"1e7275aa0b0bc738eadd452c8b1557a6ab489236\", \"boringssl_crypto_tests\": \"597684b8f75536b25d9a0ff9d05c6fb0cb9d6359\", \"boringssl_ssl_tests\": \"669a5f3f8ea8b671ffa0ab93fd89960cc05511ed\", \"browser_switcher_bho_unittests\": \"fcd32ab9de5b89ef880e60175aa95c8f7d017ca1\", \"browser_tests\": \"011020989e69051c94d5d6d24ddd36d5e4d73440\", \"capture_unittests\": \"e1595419f86b5635292efd1ec26e128ef034f10f\", \"cast_unittests\": \"580be0087a29a15ddcc26467f8ffff21ee83400a\", \"cc_unittests\": \"dcaf2f430cec0e99d4e5c8b93dd07ded78ddfdc2\", \"chrome_app_unittests\": \"c3588d01e11a1d33e42e5ee34ded3ccc5d3ef4d4\", \"chrome_cleaner_unittests\": \"ded52f882e920c9c5c5f9fa6708d38ad053c95b6\", \"chrome_elf_unittests\": \"408ec7d35a3e8232cddc03e8d2c6d2e082b9b824\", \"chromedriver_unittests\": \"54ad96ce2e646d67077ef592d9d3318ebee82e2b\", \"components_browsertests\": \"8b2dd9ce0db8c6df95590f1b550ac09845d38c52\", \"components_unittests\": \"fc4820beeb2816f82285f05ea330dd15044e8042\", \"compositor_unittests\": \"60c61ee4f6730f4ff68577deda8bad9f95d18c49\", \"content_browsertests\": \"69a29c06212960c17d9777b553755b905e594e2c\", \"content_unittests\": \"c311b62fb5b14121466a6ab5246defb28ea79b59\", \"courgette_unittests\": \"d8a0d9f17145fff91765464aede436e8df727cdc\", \"crashpad_tests\": \"f37670e6684de3c164ac3872d78ce6b69a563956\", \"cronet_tests\": \"27d91acc74fce34b218ce111819bd98d922ecc13\", \"cronet_unittests\": \"203ed233f4c08f248398d6546a90486289f59143\", \"crypto_unittests\": \"09986941194f65b837be417bcea77a27a24910f7\", \"delayloads_unittests\": \"98814b84355801ce294842a8c24918c7b53f9195\", \"device_unittests\": \"0cb18fb6710e11670de426c52b24dcc3afac3630\", \"display_unittests\": \"fb11134210fbed967136618cbd950ff991c3753c\", \"elevation_service_unittests\": \"6991fe82904a9d76215ffd5b1d1d29ebbfd6b559\", \"events_unittests\": \"598c87bfee61db7dc6456b3afb6e5c2b7683176d\", \"extensions_browsertests\": \"a815b2e84ccb6c83166fbb006a4eb0b96774a189\", \"extensions_unittests\": \"c51918b206b401886929b9792af9b21efe4bd058\", \"filesystem_service_unittests\": \"beff690899fdce3f0a2af1727f8eb4d1ca9d1644\", \"gcm_unit_tests\": \"4798682decf665cc15ee6bc72e3baa28c2803484\", \"gcp_unittests\": \"fed9a08ea9ac8f08db351318c32b0b632fe5df25\", \"gfx_unittests\": \"0e6b9d9e9cd7ca128c65bd7bcc932a2c4e0440f1\", \"gin_unittests\": \"74f91655d9387d81c97071006ca28ed7088198da\", \"google_apis_unittests\": \"e398b809b64f94d84b08f58aa112c654639d7330\", \"gpu_unittests\": \"ed192c5f413bfabbf5ba26f72efc590109d64243\", \"gwp_asan_unittests\": \"2448bb4b1acab09f3c9f0f7ba92bb69f4f7cdbae\", \"headless_browsertests\": \"2d916993023d2d5ac0b81388c03e2c8d62346d2c\", \"headless_unittests\": \"5b22dcf4f6f277911feea6d5e2d48cee5495e0cf\", \"install_static_unittests\": \"5ec7fc186401172881551a13aa3248c333ff90dc\", \"installer_util_unittests\": \"bc61d96c6ac88f64f4e0e8433755be90b83530c7\", \"interactive_ui_tests\": \"1aac881d4e623ef9eef98736fa6224a411773f5b\", \"ipc_tests\": \"282686ac3e2aac8c2eda8627c97f8db26f86fead\", \"jingle_unittests\": \"0d0f2f4d25db60f277bdc01513cb6b8239ec3297\", \"latency_unittests\": \"d8de3ee1bc8ddfa209b988f41659896230b25453\", \"libjingle_xmpp_unittests\": \"67efc1a4ca21a7fee07a0e4fdd214a0a684ba48a\", \"liburlpattern_unittests\": \"57111b31f53485f7bcbd0150ccf354776bf25b6d\", \"media_blink_unittests\": \"2a3243beb57ac84e956e219a294affb625da2365\", \"media_unittests\": \"eca5fb0f2655ecc4cdb5341485374802a2fbcc2e\", \"message_center_unittests\": \"82b697e02d4b29eaff648b1517c261ea75e81862\", \"midi_unittests\": \"c14f0a6ce0bfdd9cfb0853f2c0a365496d2ab25a\", \"mojo_core_unittests\": \"95df3c642581a30ec47cae6c8011097a1e411832\", \"mojo_unittests\": \"ce917c5be4a896dc098cb0fb7cebceff88a73aa2\", \"nacl_loader_unittests\": \"2867a5526582a20905f8d93dc7bbf75727a8c69d\", \"native_theme_unittests\": \"c270fa46d90fe32b4838e6795db1b280652035c4\", \"net_unittests\": \"65eed8d9d654c3664e8392bf9ebb09b4ff2dc422\", \"notification_helper_unittests\": \"8eec416e4103c0926185775304599723de50499e\", \"pdf_unittests\": \"0034c9820235aabf90119de1ae3b85efacc7f2fe\", \"perfetto_unittests\": \"963c3a4370646894f3ef5845029a4625aa886814\", \"ppapi_unittests\": \"90c764b6404b33f3f52b316366dbe69f31fe736d\", \"printing_unittests\": \"731b96890282aef53471edfe99b209eb7cc10804\", \"remoting_unittests\": \"cfa36f4b0e627127ceb95d81d9406b977555c628\", \"sbox_integration_tests\": \"6319dcc269a3b73ce3c00d9d306b69a9b7d88b01\", \"sbox_unittests\": \"2ef2b65f2c2b88910e4a4e5db9219206bc779471\", \"sbox_validation_tests\": \"c2c5be512b6332533b9a0cbce027db5b7907d827\", \"service_manager_unittests\": \"4f4c6c1053448770953d0b5f11a2fa4e46762281\", \"services_unittests\": \"71b0819ed884a3b0eeb084fa286752477e6f2117\", \"setup_unittests\": \"320fa6c5577331147e3521df837c3299a40c8968\", \"shell_dialogs_unittests\": \"c6c63375cc3d89ba16faa0ffdc94f2dd722b30b9\", \"skia_unittests\": \"2fe19544f4ba15e8e22e54f00ed4a4662982c897\", \"snapshot_unittests\": \"bd643139b383f790a505bb7de9fa624317eb3785\", \"sql_unittests\": \"7495831012c8e38f12d0d8c9fdda7baa441e4f96\", \"storage_unittests\": \"618a981596bcb22f4a407db56f6bec0334040dd4\", \"sync_integration_tests\": \"967c8736bccb0809b0a1cb08df92b4a7915742e4\", \"traffic_annotation_auditor_unittests\": \"d59c19663366bdeaa86b818d4259daa1a5f76b86\", \"ui_base_unittests\": \"e1cd5f0cb8e8201046402aa752460035a665dfcf\", \"ui_touch_selection_unittests\": \"9ef9b0815e0b3f83e1cefdd362b361c69b395b35\", \"unit_tests\": \"5f12ee70352cfc27b0f0e13a55486b6c41e0f87a\", \"updater_tests\": \"5d3f8f42fe00e140ac5f5e45cf341d5bf44e59e1\", \"url_unittests\": \"671c2a4dbb4316cebd1c898558525df1a71b30bc\", \"views_unittests\": \"b50865c9e49aec8097787968781f4d41e703c481\", \"viz_unittests\": \"ab29b939f6742088f53523c80582f46fd7a04c1c\", \"vr_common_unittests\": \"33dfe09b520e3994838620475eade8682bb28e12\", \"vr_pixeltests\": \"e2dea877ec949628565535f2b15cd69b7b0aaa10\", \"weblayer_browsertests\": \"7923acb830b08d4ac772a3db3dda959ef0efc2c2\", \"weblayer_unittests\": \"ab617ecde353c9ff3f8e8fdc11c431fd5bd4e9f4\", \"wm_unittests\": \"3da531c033c57224b1df4ee7d9c3aa8d56d1cca5\", \"wtf_unittests\": \"5450ac8c0ac8a0a7b6a908d40079269cd1314b69\", \"zlib_unittests\": \"32478b07fc33d1ac05b68bcb45fbf6857da4ae68\", \"zucchini_unittests\": \"0b675579fb8fedcd69dd11e6267efe72c4bad662\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"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-7-2fjn\"], \"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\": [\"5673-2413400\"], \"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": "1611939460531152",
"status": "COMPLETED",
"status_changed_ts": "1611946176440713",
"tags": [
"build_address:luci.chromium.ci/win-asan/17071",
"builder:win-asan",
"buildset:commit/git/076ee09508aadd01c7c67e424660a552226f4447",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/076ee09508aadd01c7c67e424660a552226f4447",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022183166646652896",
"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/8856753104644621216/+/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:5167dfa41ebca910",
"user_agent:luci-scheduler"
],
"updated_ts": "1611946176444204",
"url": "https://ci.chromium.org/b/8856753104644621216",
"utcnow_ts": "1611946176834875"
}
}
[18:49:36.842] Invocation finished in 1h58m38.577555561s with status SUCCEEDED