[08:14:26.781] New invocation is queued and will start shortly
[08:14:27.879] Starting the invocation (attempt 1)
[08:14:27.950] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:14:27.951] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:14:27.951] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021581484825667024",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"4340fbdb1984d143d4eb6d20e363f6d9dd7dbc5d\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9021581484825667024",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/4340fbdb1984d143d4eb6d20e363f6d9dd7dbc5d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4340fbdb1984d143d4eb6d20e363f6d9dd7dbc5d",
"gitiles_ref:refs/heads/master"
]
}
[08:14:28.779] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1612512868058595",
"id": "8856151422821241216",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4340fbdb1984d143d4eb6d20e363f6d9dd7dbc5d\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612512868472279",
"tags": [
"build_address:luci.chromium.ci/win-asan/17177",
"builder:win-asan",
"buildset:commit/git/4340fbdb1984d143d4eb6d20e363f6d9dd7dbc5d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4340fbdb1984d143d4eb6d20e363f6d9dd7dbc5d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021581484825667024",
"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/8856151422821241216/+/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": "1612512868472405",
"url": "https://ci.chromium.org/b/8856151422821241216",
"utcnow_ts": "1612512868750265"
}
}
[08:14:28.779] Task URL: https://ci.chromium.org/b/8856151422821241216
[08:14:28.779] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:2:0) after 1m0s
[08:15:28.857] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:2:0)
[08:15:28.857] Timer tick, asking Buildbucket for the build status
[08:15:29.850] Build 8856151422821241216: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:15:29.850] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:3:0) after 1m0s
[08:16:29.868] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:3:0)
[08:16:29.868] Timer tick, asking Buildbucket for the build status
[08:16:29.958] Build 8856151422821241216: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:16:29.958] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:4:0) after 1m0s
[08:17:29.966] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:4:0)
[08:17:29.966] Timer tick, asking Buildbucket for the build status
[08:17:30.258] Build 8856151422821241216: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:17:30.258] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:5:0) after 1m0s
[08:18:30.369] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:5:0)
[08:18:30.369] Timer tick, asking Buildbucket for the build status
[08:18:30.443] Build 8856151422821241216: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:18:30.443] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:6:0) after 1m0s
[08:19:04.712] Received PubSub notification, asking Buildbucket for the build status
[08:19:04.775] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:30.458] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:6:0)
[08:19:30.458] Timer tick, asking Buildbucket for the build status
[08:19:30.803] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:30.803] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:8:0) after 1m0s
[08:20:30.936] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:8:0)
[08:20:30.936] Timer tick, asking Buildbucket for the build status
[08:20:31.086] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:31.086] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:9:0) after 1m0s
[08:21:31.060] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:9:0)
[08:21:31.060] Timer tick, asking Buildbucket for the build status
[08:21:31.188] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:31.188] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:10:0) after 1m0s
[08:22:31.513] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:10:0)
[08:22:31.513] Timer tick, asking Buildbucket for the build status
[08:22:31.691] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:31.691] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:11:0) after 1m0s
[08:23:31.758] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:11:0)
[08:23:31.758] Timer tick, asking Buildbucket for the build status
[08:23:32.027] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:32.027] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:12:0) after 1m0s
[08:24:32.243] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:12:0)
[08:24:32.243] Timer tick, asking Buildbucket for the build status
[08:24:32.638] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:32.638] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:13:0) after 1m0s
[08:25:32.681] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:13:0)
[08:25:32.681] Timer tick, asking Buildbucket for the build status
[08:25:32.753] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:32.753] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:14:0) after 1m0s
[08:26:32.763] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:14:0)
[08:26:32.763] Timer tick, asking Buildbucket for the build status
[08:26:32.900] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:32.900] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:15:0) after 1m0s
[08:27:32.955] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:15:0)
[08:27:32.955] Timer tick, asking Buildbucket for the build status
[08:27:33.221] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:33.221] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:16:0) after 1m0s
[08:28:33.332] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:16:0)
[08:28:33.332] Timer tick, asking Buildbucket for the build status
[08:28:33.446] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:33.446] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:17:0) after 1m0s
[08:29:33.437] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:17:0)
[08:29:33.437] Timer tick, asking Buildbucket for the build status
[08:29:33.535] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:33.535] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:18:0) after 1m0s
[08:30:33.586] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:18:0)
[08:30:33.586] Timer tick, asking Buildbucket for the build status
[08:30:33.646] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:33.646] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:19:0) after 1m0s
[08:31:33.691] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:19:0)
[08:31:33.691] Timer tick, asking Buildbucket for the build status
[08:31:33.754] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:33.754] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:20:0) after 1m0s
[08:32:33.861] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:20:0)
[08:32:33.861] Timer tick, asking Buildbucket for the build status
[08:32:34.018] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:34.018] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:21:0) after 1m0s
[08:33:34.203] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:21:0)
[08:33:34.203] Timer tick, asking Buildbucket for the build status
[08:33:34.319] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:34.319] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:22:0) after 1m0s
[08:34:34.625] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:22:0)
[08:34:34.625] Timer tick, asking Buildbucket for the build status
[08:34:34.727] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:34.727] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:23:0) after 1m0s
[08:35:34.841] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:23:0)
[08:35:34.841] Timer tick, asking Buildbucket for the build status
[08:35:34.898] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:34.898] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:24:0) after 1m0s
[08:36:35.095] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:24:0)
[08:36:35.095] Timer tick, asking Buildbucket for the build status
[08:36:35.215] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:35.215] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:25:0) after 1m0s
[08:37:35.233] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:25:0)
[08:37:35.233] Timer tick, asking Buildbucket for the build status
[08:37:35.289] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:35.289] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:26:0) after 1m0s
[08:38:35.368] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:26:0)
[08:38:35.368] Timer tick, asking Buildbucket for the build status
[08:38:35.507] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:35.507] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:27:0) after 1m0s
[08:39:35.596] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:27:0)
[08:39:35.596] Timer tick, asking Buildbucket for the build status
[08:39:35.683] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:35.683] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:28:0) after 1m0s
[08:40:35.695] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:28:0)
[08:40:35.695] Timer tick, asking Buildbucket for the build status
[08:40:35.749] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:35.749] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:29:0) after 1m0s
[08:41:35.775] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:29:0)
[08:41:35.775] Timer tick, asking Buildbucket for the build status
[08:41:35.843] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:35.843] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:30:0) after 1m0s
[08:42:35.851] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:30:0)
[08:42:35.851] Timer tick, asking Buildbucket for the build status
[08:42:36.018] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:36.018] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:31:0) after 1m0s
[08:43:36.065] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:31:0)
[08:43:36.065] Timer tick, asking Buildbucket for the build status
[08:43:36.199] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:36.199] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:32:0) after 1m0s
[08:44:36.303] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:32:0)
[08:44:36.303] Timer tick, asking Buildbucket for the build status
[08:44:36.596] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:36.596] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:33:0) after 1m0s
[08:45:36.690] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:33:0)
[08:45:36.690] Timer tick, asking Buildbucket for the build status
[08:45:36.822] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:36.822] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:34:0) after 1m0s
[08:46:36.904] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:34:0)
[08:46:36.904] Timer tick, asking Buildbucket for the build status
[08:46:37.283] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:37.283] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:35:0) after 1m0s
[08:47:37.387] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:35:0)
[08:47:37.387] Timer tick, asking Buildbucket for the build status
[08:47:37.494] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:37.494] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:36:0) after 1m0s
[08:48:37.524] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:36:0)
[08:48:37.524] Timer tick, asking Buildbucket for the build status
[08:48:37.708] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:37.708] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:37:0) after 1m0s
[08:49:37.788] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:37:0)
[08:49:37.788] Timer tick, asking Buildbucket for the build status
[08:49:38.293] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:38.293] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:38:0) after 1m0s
[08:50:38.379] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:38:0)
[08:50:38.379] Timer tick, asking Buildbucket for the build status
[08:50:38.535] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:38.535] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:39:0) after 1m0s
[08:51:38.797] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:39:0)
[08:51:38.797] Timer tick, asking Buildbucket for the build status
[08:51:38.904] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:38.904] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:40:0) after 1m0s
[08:52:38.947] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:40:0)
[08:52:38.947] Timer tick, asking Buildbucket for the build status
[08:52:39.126] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:39.126] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:41:0) after 1m0s
[08:53:39.140] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:41:0)
[08:53:39.140] Timer tick, asking Buildbucket for the build status
[08:53:39.417] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:39.417] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:42:0) after 1m0s
[08:54:39.477] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:42:0)
[08:54:39.477] Timer tick, asking Buildbucket for the build status
[08:54:39.675] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:39.675] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:43:0) after 1m0s
[08:55:39.710] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:43:0)
[08:55:39.710] Timer tick, asking Buildbucket for the build status
[08:55:41.042] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:41.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:44:0) after 1m0s
[08:56:41.138] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:44:0)
[08:56:41.138] Timer tick, asking Buildbucket for the build status
[08:56:41.298] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:41.298] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:45:0) after 1m0s
[08:57:41.404] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:45:0)
[08:57:41.404] Timer tick, asking Buildbucket for the build status
[08:57:41.659] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:41.659] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:46:0) after 1m0s
[08:58:41.772] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:46:0)
[08:58:41.772] Timer tick, asking Buildbucket for the build status
[08:58:41.839] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:41.839] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:47:0) after 1m0s
[08:59:41.925] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:47:0)
[08:59:41.925] Timer tick, asking Buildbucket for the build status
[08:59:42.043] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:42.043] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:48:0) after 1m0s
[09:00:42.129] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:48:0)
[09:00:42.129] Timer tick, asking Buildbucket for the build status
[09:00:42.192] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:42.192] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:49:0) after 1m0s
[09:01:42.227] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:49:0)
[09:01:42.227] Timer tick, asking Buildbucket for the build status
[09:01:42.526] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:42.526] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:50:0) after 1m0s
[09:02:42.554] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:50:0)
[09:02:42.554] Timer tick, asking Buildbucket for the build status
[09:02:42.707] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:42.707] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:51:0) after 1m0s
[09:03:42.793] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:51:0)
[09:03:42.794] Timer tick, asking Buildbucket for the build status
[09:03:42.863] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:42.863] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:52:0) after 1m0s
[09:04:42.956] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:52:0)
[09:04:42.956] Timer tick, asking Buildbucket for the build status
[09:04:43.097] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:43.097] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:53:0) after 1m0s
[09:05:43.242] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:53:0)
[09:05:43.242] Timer tick, asking Buildbucket for the build status
[09:05:43.318] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:43.319] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:54:0) after 1m0s
[09:06:43.352] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:54:0)
[09:06:43.353] Timer tick, asking Buildbucket for the build status
[09:06:43.418] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:43.418] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:55:0) after 1m0s
[09:07:43.547] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:55:0)
[09:07:43.547] Timer tick, asking Buildbucket for the build status
[09:07:43.747] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:43.747] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:56:0) after 1m0s
[09:08:43.807] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:56:0)
[09:08:43.807] Timer tick, asking Buildbucket for the build status
[09:08:43.926] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:43.926] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:57:0) after 1m0s
[09:09:44.014] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:57:0)
[09:09:44.014] Timer tick, asking Buildbucket for the build status
[09:09:44.112] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:44.112] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:58:0) after 1m0s
[09:10:44.132] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:58:0)
[09:10:44.132] Timer tick, asking Buildbucket for the build status
[09:10:44.212] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:44.212] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:59:0) after 1m0s
[09:11:44.204] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:59:0)
[09:11:44.204] Timer tick, asking Buildbucket for the build status
[09:11:44.424] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:44.424] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:60:0) after 1m0s
[09:12:44.543] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:60:0)
[09:12:44.543] Timer tick, asking Buildbucket for the build status
[09:12:44.606] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:44.606] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:61:0) after 1m0s
[09:13:44.682] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:61:0)
[09:13:44.682] Timer tick, asking Buildbucket for the build status
[09:13:44.780] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:44.780] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:62:0) after 1m0s
[09:14:44.857] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:62:0)
[09:14:44.857] Timer tick, asking Buildbucket for the build status
[09:14:44.996] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:44.996] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:63:0) after 1m0s
[09:15:45.150] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:63:0)
[09:15:45.150] Timer tick, asking Buildbucket for the build status
[09:15:45.336] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:45.336] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:64:0) after 1m0s
[09:16:45.554] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:64:0)
[09:16:45.554] Timer tick, asking Buildbucket for the build status
[09:16:45.739] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:45.739] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:65:0) after 1m0s
[09:17:45.848] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:65:0)
[09:17:45.848] Timer tick, asking Buildbucket for the build status
[09:17:45.900] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:45.900] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:66:0) after 1m0s
[09:18:45.891] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:66:0)
[09:18:45.891] Timer tick, asking Buildbucket for the build status
[09:18:46.212] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:46.212] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:67:0) after 1m0s
[09:19:46.235] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:67:0)
[09:19:46.235] Timer tick, asking Buildbucket for the build status
[09:19:46.349] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:46.349] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:68:0) after 1m0s
[09:20:46.384] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:68:0)
[09:20:46.384] Timer tick, asking Buildbucket for the build status
[09:20:46.607] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:46.607] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:69:0) after 1m0s
[09:21:46.635] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:69:0)
[09:21:46.635] Timer tick, asking Buildbucket for the build status
[09:21:46.734] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:46.734] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:70:0) after 1m0s
[09:22:46.762] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:70:0)
[09:22:46.762] Timer tick, asking Buildbucket for the build status
[09:22:46.906] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:46.906] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:71:0) after 1m0s
[09:23:46.987] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:71:0)
[09:23:46.987] Timer tick, asking Buildbucket for the build status
[09:23:47.100] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:47.100] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:72:0) after 1m0s
[09:24:47.164] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:72:0)
[09:24:47.164] Timer tick, asking Buildbucket for the build status
[09:24:47.568] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:47.568] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:73:0) after 1m0s
[09:25:47.654] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:73:0)
[09:25:47.654] Timer tick, asking Buildbucket for the build status
[09:25:47.700] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:47.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:74:0) after 1m0s
[09:26:47.787] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:74:0)
[09:26:47.787] Timer tick, asking Buildbucket for the build status
[09:26:47.854] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:47.854] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:75:0) after 1m0s
[09:27:47.863] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:75:0)
[09:27:47.863] Timer tick, asking Buildbucket for the build status
[09:27:48.114] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:48.114] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:76:0) after 1m0s
[09:28:48.120] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:76:0)
[09:28:48.120] Timer tick, asking Buildbucket for the build status
[09:28:48.381] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:48.381] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:77:0) after 1m0s
[09:29:48.425] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:77:0)
[09:29:48.425] Timer tick, asking Buildbucket for the build status
[09:29:48.539] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:48.539] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:78:0) after 1m0s
[09:30:48.596] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:78:0)
[09:30:48.596] Timer tick, asking Buildbucket for the build status
[09:30:48.660] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:48.660] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:79:0) after 1m0s
[09:31:48.704] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:79:0)
[09:31:48.704] Timer tick, asking Buildbucket for the build status
[09:31:48.793] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:48.793] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:80:0) after 1m0s
[09:32:48.845] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:80:0)
[09:32:48.845] Timer tick, asking Buildbucket for the build status
[09:32:49.108] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:49.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:81:0) after 1m0s
[09:33:49.199] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:81:0)
[09:33:49.199] Timer tick, asking Buildbucket for the build status
[09:33:49.521] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:49.521] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:82:0) after 1m0s
[09:34:49.589] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:82:0)
[09:34:49.589] Timer tick, asking Buildbucket for the build status
[09:34:49.920] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:49.920] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:83:0) after 1m0s
[09:35:49.927] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:83:0)
[09:35:49.927] Timer tick, asking Buildbucket for the build status
[09:35:50.052] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:50.052] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:84:0) after 1m0s
[09:36:50.096] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:84:0)
[09:36:50.096] Timer tick, asking Buildbucket for the build status
[09:36:50.331] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:50.331] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:85:0) after 1m0s
[09:37:50.438] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:85:0)
[09:37:50.438] Timer tick, asking Buildbucket for the build status
[09:37:50.539] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:50.540] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:86:0) after 1m0s
[09:38:50.575] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:86:0)
[09:38:50.575] Timer tick, asking Buildbucket for the build status
[09:38:50.680] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:50.680] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:87:0) after 1m0s
[09:39:50.793] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:87:0)
[09:39:50.793] Timer tick, asking Buildbucket for the build status
[09:39:50.954] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:50.954] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:88:0) after 1m0s
[09:40:50.976] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:88:0)
[09:40:50.976] Timer tick, asking Buildbucket for the build status
[09:40:51.082] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:51.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:89:0) after 1m0s
[09:41:51.097] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:89:0)
[09:41:51.097] Timer tick, asking Buildbucket for the build status
[09:41:51.467] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:51.467] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:90:0) after 1m0s
[09:42:52.922] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:90:0)
[09:42:52.922] Timer tick, asking Buildbucket for the build status
[09:42:53.052] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:53.052] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:91:0) after 1m0s
[09:43:53.096] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:91:0)
[09:43:53.096] Timer tick, asking Buildbucket for the build status
[09:43:53.180] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:53.180] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:92:0) after 1m0s
[09:44:53.206] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:92:0)
[09:44:53.206] Timer tick, asking Buildbucket for the build status
[09:44:53.904] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:53.904] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:93:0) after 1m0s
[09:45:53.926] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:93:0)
[09:45:53.926] Timer tick, asking Buildbucket for the build status
[09:45:54.280] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:54.280] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:94:0) after 1m0s
[09:46:54.326] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:94:0)
[09:46:54.326] Timer tick, asking Buildbucket for the build status
[09:46:54.719] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:54.719] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:95:0) after 1m0s
[09:47:54.705] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:95:0)
[09:47:54.705] Timer tick, asking Buildbucket for the build status
[09:47:54.765] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:54.765] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:96:0) after 1m0s
[09:48:54.825] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:96:0)
[09:48:54.825] Timer tick, asking Buildbucket for the build status
[09:48:55.257] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:55.257] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:97:0) after 1m0s
[09:49:55.331] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:97:0)
[09:49:55.331] Timer tick, asking Buildbucket for the build status
[09:49:55.466] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:55.466] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:98:0) after 1m0s
[09:50:55.522] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:98:0)
[09:50:55.522] Timer tick, asking Buildbucket for the build status
[09:50:55.603] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:55.603] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:99:0) after 1m0s
[09:51:55.622] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:99:0)
[09:51:55.622] Timer tick, asking Buildbucket for the build status
[09:51:55.704] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:55.704] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:100:0) after 1m0s
[09:52:55.809] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:100:0)
[09:52:55.809] Timer tick, asking Buildbucket for the build status
[09:52:55.944] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:55.944] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:101:0) after 1m0s
[09:53:56.020] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:101:0)
[09:53:56.020] Timer tick, asking Buildbucket for the build status
[09:53:56.173] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:56.173] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:102:0) after 1m0s
[09:54:56.219] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:102:0)
[09:54:56.219] Timer tick, asking Buildbucket for the build status
[09:54:56.331] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:56.331] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:103:0) after 1m0s
[09:55:56.472] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:103:0)
[09:55:56.472] Timer tick, asking Buildbucket for the build status
[09:55:56.586] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:56.586] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:104:0) after 1m0s
[09:56:56.605] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:104:0)
[09:56:56.605] Timer tick, asking Buildbucket for the build status
[09:56:57.011] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:57.011] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:105:0) after 1m0s
[09:57:57.096] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:105:0)
[09:57:57.096] Timer tick, asking Buildbucket for the build status
[09:57:57.991] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:57.991] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:106:0) after 1m0s
[09:58:58.038] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:106:0)
[09:58:58.038] Timer tick, asking Buildbucket for the build status
[09:58:58.145] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:58.145] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:107:0) after 1m0s
[09:59:58.255] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:107:0)
[09:59:58.255] Timer tick, asking Buildbucket for the build status
[09:59:58.352] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:58.352] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:108:0) after 1m0s
[10:00:58.421] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:108:0)
[10:00:58.421] Timer tick, asking Buildbucket for the build status
[10:00:58.742] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:58.742] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:109:0) after 1m0s
[10:01:58.804] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:109:0)
[10:01:58.804] Timer tick, asking Buildbucket for the build status
[10:01:58.963] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:58.964] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:110:0) after 1m0s
[10:02:59.061] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:110:0)
[10:02:59.061] Timer tick, asking Buildbucket for the build status
[10:02:59.148] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:59.148] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:111:0) after 1m0s
[10:03:59.238] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:111:0)
[10:03:59.238] Timer tick, asking Buildbucket for the build status
[10:03:59.307] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:59.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:112:0) after 1m0s
[10:04:59.338] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:112:0)
[10:04:59.338] Timer tick, asking Buildbucket for the build status
[10:04:59.986] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:59.986] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:113:0) after 1m0s
[10:06:00.065] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:113:0)
[10:06:00.065] Timer tick, asking Buildbucket for the build status
[10:06:00.731] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:00.731] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:114:0) after 1m0s
[10:07:00.748] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:114:0)
[10:07:00.748] Timer tick, asking Buildbucket for the build status
[10:07:00.855] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:00.855] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:115:0) after 1m0s
[10:08:00.900] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:115:0)
[10:08:00.900] Timer tick, asking Buildbucket for the build status
[10:08:01.620] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:01.620] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:116:0) after 1m0s
[10:09:01.668] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:116:0)
[10:09:01.668] Timer tick, asking Buildbucket for the build status
[10:09:01.854] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:01.854] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:117:0) after 1m0s
[10:10:01.892] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:117:0)
[10:10:01.892] Timer tick, asking Buildbucket for the build status
[10:10:02.537] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:02.537] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:118:0) after 1m0s
[10:11:02.575] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:118:0)
[10:11:02.575] Timer tick, asking Buildbucket for the build status
[10:11:02.696] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:02.696] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:119:0) after 1m0s
[10:12:02.759] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:119:0)
[10:12:02.759] Timer tick, asking Buildbucket for the build status
[10:12:02.868] Build 8856151422821241216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:02.868] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021581484825667024:120:0) after 1m0s
[10:12:32.318] Received PubSub notification, asking Buildbucket for the build status
[10:12:32.415] Build 8856151422821241216: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[10:12:32.415] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1612519950692629",
"created_by": "project:chromium",
"created_ts": "1612512868058595",
"id": "8856151422821241216",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4340fbdb1984d143d4eb6d20e363f6d9dd7dbc5d\"}}",
"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\": \"4340fbdb1984d143d4eb6d20e363f6d9dd7dbc5d\", \"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-0-be5b\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17177, \"clang_revision\": \"llvmorg-12-init-17251-g6de48655-2\", \"got_angle_revision\": \"e5c5efc05f23e55e60706ff67c5d60022373d06a\", \"got_dawn_revision\": \"2b9707d3369addfef7f7f7aafa6416036e4aad52\", \"got_nacl_revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"got_revision\": \"4340fbdb1984d143d4eb6d20e363f6d9dd7dbc5d\", \"got_revision_cp\": \"refs/heads/master@{#851023}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"14dcbed947ce755af1fcbfb3ba8e643b4e769860\", \"got_v8_revision\": \"3d9850164408ec1c4b4ea60c4515b85853853794\", \"got_v8_revision_cp\": \"refs/heads/9.0.134@{#1}\", \"got_webrtc_revision\": \"1d71fd9c61db8f1dcf30b5d0625e514b91200926\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33155}\", \"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\": \"4340fbdb1984d143d4eb6d20e363f6d9dd7dbc5d\", \"swarm_hashes_refs/heads/master(at){#851023}_without_patch\": {\"absl_hardening_tests\": \"1f5fcf4cef2a4c2671e0a4d588de288cff942a0c\", \"accessibility_unittests\": \"77433f3f9dec958fee8db9ce9b9c36e2bd7da975\", \"angle_unittests\": \"f73d38b77a64559b35a3de74c37a23d0e34be0fb\", \"app_shell_unittests\": \"749487b60655d4b5bfa4572ad1d8a692d4d9a020\", \"aura_unittests\": \"fcf3c7d8de963fa52885a7b4167fa78dd464cf7a\", \"base_unittests\": \"d50c5c88b53eefa4620beb4df581c7d9e1b4b050\", \"base_util_unittests\": \"4eaca542afa368dcf490d16cf6771cea61e0acbe\", \"blink_common_unittests\": \"acb0982cf3c699f590cca2410b12e10850c84432\", \"blink_fuzzer_unittests\": \"7b8e615e3c0e2ae6e2ffb0ed24380a8dbd053686\", \"blink_heap_unittests\": \"d658eb271b862de6da8e1bcf25bfc587a26b3c08\", \"blink_platform_unittests\": \"63c6b9363ec5eb911960cc5ba51adc5cc18f44d9\", \"blink_unittests\": \"4c7ad1c1e6ea633d140de4848820a1968d325373\", \"boringssl_crypto_tests\": \"e8de74ac4968d9632e96ab9632588ca7bcdb6dd3\", \"boringssl_ssl_tests\": \"8bddd9dbf829f71279c49587b0f2c3648a32076d\", \"browser_switcher_bho_unittests\": \"1810e7e4e00fcf145c1bf7a73413bfbc8b7710c2\", \"browser_tests\": \"686048cab5323d886baae0f621e50b9b541b168a\", \"capture_unittests\": \"cffcd831c4597484e799ba696c757a33dcadcb9d\", \"cast_unittests\": \"31d0af172061bf9b5924189215c02b3648408238\", \"cc_unittests\": \"545c91f1e3c64c4ffee80576043aa8bf5abe5835\", \"chrome_app_unittests\": \"0a65b333b01be4b2a9d4fee4f492117acb3e6c29\", \"chrome_cleaner_unittests\": \"9140c11a4342d58ccb1deb3bcf8bbb6af1c14d96\", \"chrome_elf_unittests\": \"819357ef50441aacb19cb167191e25f8ce99232c\", \"chromedriver_unittests\": \"4b33c805304669378cc5f9fc75ea7c950b4a063e\", \"components_browsertests\": \"a20cf526966697bf99be6797d5f6e466222f3031\", \"components_unittests\": \"231c2f4d0b70d6788810ad70884d522dfc3bd43c\", \"compositor_unittests\": \"f83f9df1f626cc0effcc14c8303ffc95b18d6d68\", \"content_browsertests\": \"06a17bf4820456884a97649a8d1d64812fee7207\", \"content_unittests\": \"f19c6398f9ddb964ff55c49368298d17f01d96c4\", \"courgette_unittests\": \"e2f6b0806d5bce6263a08dd7586e12a2e041b1a6\", \"crashpad_tests\": \"b7b78bc207eb664d71a9d472e258a7dfaedf9016\", \"cronet_tests\": \"fd5b4506bf2043a2306f3e15929948afb8b24c7d\", \"cronet_unittests\": \"f809295c56c8679581cf34762f9bd4f6f482db81\", \"crypto_unittests\": \"2eb7907c7274f5b5420b0e1109ef547af6978e75\", \"delayloads_unittests\": \"6b4e8efffe75de5be7adbf4b424eaf6556a1a3a4\", \"device_unittests\": \"9bc83ebf87c3e6b87ee884eeed5f45dd03ca13a9\", \"display_unittests\": \"6b5a4d325b18a414395844ec01bf60702954703c\", \"elevation_service_unittests\": \"4b4d89dca19c89ef083153e8cbcbf40b8fa5b151\", \"events_unittests\": \"23f890224fcc5929580e3db1c84de2eb1ca8f7a0\", \"extensions_browsertests\": \"ccfcc1bd1f3595b544b82edd224159111b02c2ec\", \"extensions_unittests\": \"c9496691a9ff63bd118ff4de7f53300d005cdf23\", \"filesystem_service_unittests\": \"0e9efd9b09b4759af525e1d9468568a5ac523a94\", \"gcm_unit_tests\": \"9231b34722c2996ff43ffb621dca91adf93ac8f9\", \"gcp_unittests\": \"31964fea3cfe5650afe05ec862d037bdf5106a34\", \"gfx_unittests\": \"48384a1bebfee4018073838d8d0000b7c06d0d77\", \"gin_unittests\": \"071f967cc5e5a3867c609f4b0af03628ddc7e364\", \"google_apis_unittests\": \"2fc7522a73ca239511bbc5e5e32f1400ce54b502\", \"gpu_unittests\": \"0299adaeb6e3227931ac1e874f0f729839ac4c96\", \"gwp_asan_unittests\": \"1e7286f825e7af148ca9beef362fd3f3bf89de13\", \"headless_browsertests\": \"6f627fda24b62b9ebec628fae367a11488b2bc97\", \"headless_unittests\": \"e7ac71bbc18ef24af6eb822ce32f3a552474b748\", \"install_static_unittests\": \"2f984f8b0b87290d3c08b7b720607b20a4ed78ea\", \"installer_util_unittests\": \"a28c7044b0d33acced26b57b8249b92922073ccf\", \"interactive_ui_tests\": \"edab73f15aacd468ff3e78f9e08eeb5cd2223334\", \"ipc_tests\": \"ee36f93f7c691ca5bc03cecf4b6edb3dc9d39245\", \"jingle_unittests\": \"f347c060e8ec2a07ebbb30dd627a3bd13ec2e920\", \"latency_unittests\": \"672f35e8ba5f9942bfca4f6704f82a5ba5f2e939\", \"libjingle_xmpp_unittests\": \"f57fae71bfc106df8b22b3730639632c2c25ee4a\", \"liburlpattern_unittests\": \"a553b918d6d27c7523822b5fed18c6242a5cf73e\", \"media_blink_unittests\": \"7d292a93bedd7a20e177f33420b1c1657fc79e96\", \"media_unittests\": \"19ac244c105ee3994433b751a8475649cacf2825\", \"message_center_unittests\": \"adab34990db78fd7b4bc487f6a6e330b690d6099\", \"midi_unittests\": \"df950170188c14cfcd388f0c5c3f2508604f5f79\", \"mojo_core_unittests\": \"4d354fcfa6fceed806ce006cda67a86f089e1e84\", \"mojo_unittests\": \"77e26e4c2e62e3258e8739eacb3e1366b72db2a7\", \"nacl_loader_unittests\": \"322af9116c2f9c93ef48cb197b7686767f9c2b42\", \"native_theme_unittests\": \"fd734bf5a076389c274a19db31af99c8d325bf04\", \"net_unittests\": \"f5860872bbc144b49d72206a78cf5cc64c8d7a21\", \"notification_helper_unittests\": \"343bc3c828ed65d64ed25dc042ea2591f15ddf0c\", \"pdf_unittests\": \"11eefbdf97bfd511d95344aa92f91dc63a0b0d1b\", \"perfetto_unittests\": \"28b72fd9d08d3b99466145f3bfe54261490b67fc\", \"ppapi_unittests\": \"b7aa0ecde47e5a574ba0d4eaf30370efe218ddc5\", \"printing_unittests\": \"6164ae7251e0fcd939e5f845a37fabf5458b7a07\", \"remoting_unittests\": \"ddbf20f642fad81d1b4a7f1e6e4f96710e9a7ae0\", \"sbox_integration_tests\": \"68d85973d3ef459b1f4cadff2946004bb16b6c34\", \"sbox_unittests\": \"fd057525248bbe80fde760acf60ae176a014fa3f\", \"sbox_validation_tests\": \"3e7971edf5e644386fb476471e0ceba0b801a561\", \"service_manager_unittests\": \"54b49c2406bc2ffdfe0d2f9067a6e67a9419276c\", \"services_unittests\": \"dc71439fb9d58d8843e26c603ecc280ccbf0d8fd\", \"setup_unittests\": \"506dc0d5aef9ec7dc8d9a22686a6030b7dbdf308\", \"shell_dialogs_unittests\": \"26daf7dd214e6b3df7c07e8ee7827e1ca61a5e92\", \"skia_unittests\": \"bba9f5837c6ed72c2605154c79adec4e1d25ef6a\", \"snapshot_unittests\": \"66712e8292efa050d9f41a2082acb5b4910faab9\", \"sql_unittests\": \"8aaa24ffbafa7835e1f630a399051fffe1b6541e\", \"storage_unittests\": \"32629c328464ab7f50dbb61eced753054fa3354e\", \"sync_integration_tests\": \"971c7e5ce74f8547f756c098621d696d42950bea\", \"traffic_annotation_auditor_unittests\": \"5fb1aa6d5ececf74e757cb3175b62ba8933159ce\", \"ui_base_unittests\": \"cd780fb26bfb4a1fc5abd557655d1b3f86717509\", \"ui_touch_selection_unittests\": \"f069dd57427737681dddbd112328a99d4f6a58a4\", \"unit_tests\": \"3cab370a3f87d054860ca1cab60a85046030c46b\", \"updater_tests\": \"cdedef4ff7d6fbc031db309b0eec0335142c4541\", \"url_unittests\": \"eb0255c0bea85f3b88ff304a3ebf8e88290cd187\", \"views_unittests\": \"16c510d16e121bb606202260b3834340830acb54\", \"viz_unittests\": \"67ff028dde149105552fbe6872fb1ca49c92bc44\", \"vr_common_unittests\": \"4e6390b05c8ebd726f36fcfc318db5cccd0fba27\", \"vr_pixeltests\": \"239418d428818fd7fec59311d3b8c3d2b765b598\", \"weblayer_browsertests\": \"3e5f2550adc057f886d7378dcf717cc4a5704045\", \"weblayer_unittests\": \"f9a27f669ea42f05cb80c965f3acdc2af4eb6166\", \"wm_unittests\": \"cc1fcc6576f66cae27e20feb5a9039236e43fd57\", \"wtf_unittests\": \"02a356963e75f2a573111a45d65ddb6470b31c46\", \"zlib_unittests\": \"107c49f9c346e481eb6267c17b2364ff3f8781c0\", \"zucchini_unittests\": \"7c8ff3311388421b8b1ca09ab10aa5e5d1bab421\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_02613bacee8cd1c41158238da4ce7615021ecb28a4b52e6029f89cb601af0790_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-0-be5b\"], \"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\": [\"5676-c76598c\"], \"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": "1612513144155566",
"status": "COMPLETED",
"status_changed_ts": "1612519951125215",
"tags": [
"build_address:luci.chromium.ci/win-asan/17177",
"builder:win-asan",
"buildset:commit/git/4340fbdb1984d143d4eb6d20e363f6d9dd7dbc5d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4340fbdb1984d143d4eb6d20e363f6d9dd7dbc5d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021581484825667024",
"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/8856151422821241216/+/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:518a13441a528a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1612519951132792",
"url": "https://ci.chromium.org/b/8856151422821241216",
"utcnow_ts": "1612519952402221"
}
}
[10:12:32.415] Invocation finished in 1h58m5.65175738s with status SUCCEEDED