[03:54:00.500] New invocation is queued and will start shortly
[03:54:01.647] Starting the invocation (attempt 1)
[03:54:01.713] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:54:01.714] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:54:01.714] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9018064588475876112",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"8933e0b9482364a01847a05ad57e0afb2f7679bc\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9018064588475876112",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/8933e0b9482364a01847a05ad57e0afb2f7679bc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8933e0b9482364a01847a05ad57e0afb2f7679bc",
"gitiles_ref:refs/heads/master"
]
}
[03:54:02.472] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1615866841899403",
"id": "8852634526347085680",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8933e0b9482364a01847a05ad57e0afb2f7679bc\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615866842325215",
"tags": [
"build_address:luci.chromium.ci/win-asan/17812",
"builder:win-asan",
"buildset:commit/git/8933e0b9482364a01847a05ad57e0afb2f7679bc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8933e0b9482364a01847a05ad57e0afb2f7679bc",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018064588475876112",
"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/8852634526347085680/+/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": "1615866842325388",
"url": "https://ci.chromium.org/b/8852634526347085680",
"utcnow_ts": "1615866842466268"
}
}
[03:54:02.473] Task URL: https://ci.chromium.org/b/8852634526347085680
[03:54:02.473] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:2:0) after 1m0s
[03:55:02.650] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:2:0)
[03:55:02.650] Timer tick, asking Buildbucket for the build status
[03:55:02.788] Build 8852634526347085680: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:55:02.788] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:3:0) after 1m0s
[03:56:02.990] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:3:0)
[03:56:02.990] Timer tick, asking Buildbucket for the build status
[03:56:03.064] Build 8852634526347085680: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:56:03.064] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:4:0) after 1m0s
[03:57:03.228] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:4:0)
[03:57:03.228] Timer tick, asking Buildbucket for the build status
[03:57:03.285] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:03.285] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:5:0) after 1m0s
[03:57:03.336] Received PubSub notification, asking Buildbucket for the build status
[03:57:03.405] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:03.869] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:5:0)
[03:58:03.869] Timer tick, asking Buildbucket for the build status
[03:58:03.940] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:03.940] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:7:0) after 1m0s
[03:59:04.341] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:7:0)
[03:59:04.341] Timer tick, asking Buildbucket for the build status
[03:59:04.431] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:04.431] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:8:0) after 1m0s
[04:00:04.808] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:8:0)
[04:00:04.808] Timer tick, asking Buildbucket for the build status
[04:00:04.924] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:04.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:9:0) after 1m0s
[04:01:05.672] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:9:0)
[04:01:05.672] Timer tick, asking Buildbucket for the build status
[04:01:07.954] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:07.954] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:10:0) after 1m0s
[04:02:08.077] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:10:0)
[04:02:08.077] Timer tick, asking Buildbucket for the build status
[04:02:08.177] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:08.177] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:11:0) after 1m0s
[04:03:08.301] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:11:0)
[04:03:08.301] Timer tick, asking Buildbucket for the build status
[04:03:08.429] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:08.429] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:12:0) after 1m0s
[04:04:08.482] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:12:0)
[04:04:08.482] Timer tick, asking Buildbucket for the build status
[04:04:09.009] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:09.009] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:13:0) after 1m0s
[04:05:09.125] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:13:0)
[04:05:09.125] Timer tick, asking Buildbucket for the build status
[04:05:09.191] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:09.191] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:14:0) after 1m0s
[04:06:09.281] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:14:0)
[04:06:09.281] Timer tick, asking Buildbucket for the build status
[04:06:09.457] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:09.457] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:15:0) after 1m0s
[04:07:09.498] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:15:0)
[04:07:09.498] Timer tick, asking Buildbucket for the build status
[04:07:09.698] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:09.698] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:16:0) after 1m0s
[04:08:09.716] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:16:0)
[04:08:09.716] Timer tick, asking Buildbucket for the build status
[04:08:09.878] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:09.878] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:17:0) after 1m0s
[04:09:09.935] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:17:0)
[04:09:09.935] Timer tick, asking Buildbucket for the build status
[04:09:10.022] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:10.022] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:18:0) after 1m0s
[04:10:10.079] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:18:0)
[04:10:10.079] Timer tick, asking Buildbucket for the build status
[04:10:10.157] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:10.157] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:19:0) after 1m0s
[04:11:10.346] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:19:0)
[04:11:10.346] Timer tick, asking Buildbucket for the build status
[04:11:10.418] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:10.418] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:20:0) after 1m0s
[04:12:10.757] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:20:0)
[04:12:10.758] Timer tick, asking Buildbucket for the build status
[04:12:11.023] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:11.023] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:21:0) after 1m0s
[04:13:11.424] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:21:0)
[04:13:11.424] Timer tick, asking Buildbucket for the build status
[04:13:11.492] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:11.492] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:22:0) after 1m0s
[04:14:11.608] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:22:0)
[04:14:11.608] Timer tick, asking Buildbucket for the build status
[04:14:11.691] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:11.691] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:23:0) after 1m0s
[04:15:11.772] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:23:0)
[04:15:11.772] Timer tick, asking Buildbucket for the build status
[04:15:11.848] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:11.848] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:24:0) after 1m0s
[04:16:11.897] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:24:0)
[04:16:11.897] Timer tick, asking Buildbucket for the build status
[04:16:11.961] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:11.961] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:25:0) after 1m0s
[04:17:12.004] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:25:0)
[04:17:12.004] Timer tick, asking Buildbucket for the build status
[04:17:12.152] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:12.152] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:26:0) after 1m0s
[04:18:12.192] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:26:0)
[04:18:12.192] Timer tick, asking Buildbucket for the build status
[04:18:12.362] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:12.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:27:0) after 1m0s
[04:19:12.369] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:27:0)
[04:19:12.369] Timer tick, asking Buildbucket for the build status
[04:19:12.461] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:12.461] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:28:0) after 1m0s
[04:20:12.547] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:28:0)
[04:20:12.547] Timer tick, asking Buildbucket for the build status
[04:20:12.644] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:12.644] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:29:0) after 1m0s
[04:21:12.820] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:29:0)
[04:21:12.820] Timer tick, asking Buildbucket for the build status
[04:21:12.934] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:12.934] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:30:0) after 1m0s
[04:22:13.039] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:30:0)
[04:22:13.039] Timer tick, asking Buildbucket for the build status
[04:22:13.110] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:13.110] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:31:0) after 1m0s
[04:23:13.287] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:31:0)
[04:23:13.287] Timer tick, asking Buildbucket for the build status
[04:23:13.350] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:13.350] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:32:0) after 1m0s
[04:24:13.481] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:32:0)
[04:24:13.481] Timer tick, asking Buildbucket for the build status
[04:24:13.585] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:13.585] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:33:0) after 1m0s
[04:25:14.059] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:33:0)
[04:25:14.059] Timer tick, asking Buildbucket for the build status
[04:25:14.170] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:14.170] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:34:0) after 1m0s
[04:26:14.226] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:34:0)
[04:26:14.226] Timer tick, asking Buildbucket for the build status
[04:26:14.293] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:14.293] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:35:0) after 1m0s
[04:27:14.362] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:35:0)
[04:27:14.362] Timer tick, asking Buildbucket for the build status
[04:27:14.441] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:14.441] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:36:0) after 1m0s
[04:28:14.508] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:36:0)
[04:28:14.508] Timer tick, asking Buildbucket for the build status
[04:28:14.585] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:14.585] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:37:0) after 1m0s
[04:29:14.615] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:37:0)
[04:29:14.615] Timer tick, asking Buildbucket for the build status
[04:29:14.728] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:14.728] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:38:0) after 1m0s
[04:30:14.914] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:38:0)
[04:30:14.914] Timer tick, asking Buildbucket for the build status
[04:30:14.988] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:14.988] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:39:0) after 1m0s
[04:31:15.035] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:39:0)
[04:31:15.035] Timer tick, asking Buildbucket for the build status
[04:31:15.127] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:15.127] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:40:0) after 1m0s
[04:32:15.189] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:40:0)
[04:32:15.189] Timer tick, asking Buildbucket for the build status
[04:32:15.526] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:15.526] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:41:0) after 1m0s
[04:33:15.542] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:41:0)
[04:33:15.542] Timer tick, asking Buildbucket for the build status
[04:33:15.611] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:15.611] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:42:0) after 1m0s
[04:34:15.675] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:42:0)
[04:34:15.675] Timer tick, asking Buildbucket for the build status
[04:34:15.768] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:15.768] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:43:0) after 1m0s
[04:35:15.854] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:43:0)
[04:35:15.854] Timer tick, asking Buildbucket for the build status
[04:35:15.963] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:15.963] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:44:0) after 1m0s
[04:36:16.313] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:44:0)
[04:36:16.313] Timer tick, asking Buildbucket for the build status
[04:36:16.395] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:16.395] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:45:0) after 1m0s
[04:37:16.407] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:45:0)
[04:37:16.407] Timer tick, asking Buildbucket for the build status
[04:37:16.515] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:16.515] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:46:0) after 1m0s
[04:38:16.549] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:46:0)
[04:38:16.549] Timer tick, asking Buildbucket for the build status
[04:38:16.616] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:16.616] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:47:0) after 1m0s
[04:39:16.736] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:47:0)
[04:39:16.736] Timer tick, asking Buildbucket for the build status
[04:39:16.867] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:16.867] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:48:0) after 1m0s
[04:40:17.163] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:48:0)
[04:40:17.163] Timer tick, asking Buildbucket for the build status
[04:40:21.506] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:21.506] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:49:0) after 1m0s
[04:41:21.646] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:49:0)
[04:41:21.646] Timer tick, asking Buildbucket for the build status
[04:41:21.737] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:21.737] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:50:0) after 1m0s
[04:42:22.267] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:50:0)
[04:42:22.267] Timer tick, asking Buildbucket for the build status
[04:42:22.347] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:22.347] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:51:0) after 1m0s
[04:43:22.401] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:51:0)
[04:43:22.401] Timer tick, asking Buildbucket for the build status
[04:43:22.647] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:22.647] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:52:0) after 1m0s
[04:44:22.668] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:52:0)
[04:44:22.668] Timer tick, asking Buildbucket for the build status
[04:44:22.771] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:22.771] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:53:0) after 1m0s
[04:45:22.817] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:53:0)
[04:45:22.817] Timer tick, asking Buildbucket for the build status
[04:45:23.037] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:23.037] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:54:0) after 1m0s
[04:46:23.046] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:54:0)
[04:46:23.046] Timer tick, asking Buildbucket for the build status
[04:46:23.118] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:23.118] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:55:0) after 1m0s
[04:47:23.160] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:55:0)
[04:47:23.160] Timer tick, asking Buildbucket for the build status
[04:47:23.238] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:23.238] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:56:0) after 1m0s
[04:48:23.354] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:56:0)
[04:48:23.354] Timer tick, asking Buildbucket for the build status
[04:48:23.420] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:23.420] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:57:0) after 1m0s
[04:49:23.501] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:57:0)
[04:49:23.501] Timer tick, asking Buildbucket for the build status
[04:49:23.582] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:23.582] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:58:0) after 1m0s
[04:50:23.879] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:58:0)
[04:50:23.879] Timer tick, asking Buildbucket for the build status
[04:50:23.940] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:23.940] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:59:0) after 1m0s
[04:51:24.207] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:59:0)
[04:51:24.207] Timer tick, asking Buildbucket for the build status
[04:51:24.278] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:24.278] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:60:0) after 1m0s
[04:52:24.455] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:60:0)
[04:52:24.455] Timer tick, asking Buildbucket for the build status
[04:52:24.528] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:24.528] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:61:0) after 1m0s
[04:53:24.706] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:61:0)
[04:53:24.706] Timer tick, asking Buildbucket for the build status
[04:53:24.773] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:24.773] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:62:0) after 1m0s
[04:54:25.102] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:62:0)
[04:54:25.102] Timer tick, asking Buildbucket for the build status
[04:54:25.176] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:25.176] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:63:0) after 1m0s
[04:55:25.887] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:63:0)
[04:55:25.887] Timer tick, asking Buildbucket for the build status
[04:55:25.962] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:25.962] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:64:0) after 1m0s
[04:56:26.442] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:64:0)
[04:56:26.442] Timer tick, asking Buildbucket for the build status
[04:56:26.499] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:26.499] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:65:0) after 1m0s
[04:57:26.806] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:65:0)
[04:57:26.806] Timer tick, asking Buildbucket for the build status
[04:57:26.889] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:26.889] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:66:0) after 1m0s
[04:58:26.995] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:66:0)
[04:58:26.995] Timer tick, asking Buildbucket for the build status
[04:58:27.113] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:27.113] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:67:0) after 1m0s
[04:59:27.275] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:67:0)
[04:59:27.275] Timer tick, asking Buildbucket for the build status
[04:59:27.376] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:27.376] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:68:0) after 1m0s
[05:00:27.608] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:68:0)
[05:00:27.608] Timer tick, asking Buildbucket for the build status
[05:00:27.848] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:27.848] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:69:0) after 1m0s
[05:01:28.266] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:69:0)
[05:01:28.266] Timer tick, asking Buildbucket for the build status
[05:01:28.447] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:28.447] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:70:0) after 1m0s
[05:02:28.573] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:70:0)
[05:02:28.573] Timer tick, asking Buildbucket for the build status
[05:02:28.647] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:28.647] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:71:0) after 1m0s
[05:03:28.717] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:71:0)
[05:03:28.717] Timer tick, asking Buildbucket for the build status
[05:03:28.787] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:28.787] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:72:0) after 1m0s
[05:04:28.853] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:72:0)
[05:04:28.853] Timer tick, asking Buildbucket for the build status
[05:04:28.943] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:28.944] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:73:0) after 1m0s
[05:05:29.065] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:73:0)
[05:05:29.065] Timer tick, asking Buildbucket for the build status
[05:05:29.322] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:29.322] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:74:0) after 1m0s
[05:06:29.339] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:74:0)
[05:06:29.339] Timer tick, asking Buildbucket for the build status
[05:06:29.554] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:29.554] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:75:0) after 1m0s
[05:07:29.533] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:75:0)
[05:07:29.533] Timer tick, asking Buildbucket for the build status
[05:07:29.607] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:29.607] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:76:0) after 1m0s
[05:08:29.625] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:76:0)
[05:08:29.625] Timer tick, asking Buildbucket for the build status
[05:08:29.745] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:29.745] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:77:0) after 1m0s
[05:09:29.812] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:77:0)
[05:09:29.812] Timer tick, asking Buildbucket for the build status
[05:09:29.891] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:29.891] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:78:0) after 1m0s
[05:10:29.882] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:78:0)
[05:10:29.882] Timer tick, asking Buildbucket for the build status
[05:10:30.338] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:30.338] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:79:0) after 1m0s
[05:11:30.405] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:79:0)
[05:11:30.405] Timer tick, asking Buildbucket for the build status
[05:11:35.887] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:35.887] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:80:0) after 1m0s
[05:12:36.107] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:80:0)
[05:12:36.107] Timer tick, asking Buildbucket for the build status
[05:12:36.441] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:36.441] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:81:0) after 1m0s
[05:13:36.580] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:81:0)
[05:13:36.580] Timer tick, asking Buildbucket for the build status
[05:13:36.662] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:36.662] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:82:0) after 1m0s
[05:14:36.747] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:82:0)
[05:14:36.747] Timer tick, asking Buildbucket for the build status
[05:14:36.936] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:36.936] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:83:0) after 1m0s
[05:15:36.992] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:83:0)
[05:15:36.992] Timer tick, asking Buildbucket for the build status
[05:15:37.305] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:37.305] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:84:0) after 1m0s
[05:16:37.368] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:84:0)
[05:16:37.368] Timer tick, asking Buildbucket for the build status
[05:16:37.467] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:37.467] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:85:0) after 1m0s
[05:17:37.469] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:85:0)
[05:17:37.469] Timer tick, asking Buildbucket for the build status
[05:17:37.738] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:37.738] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:86:0) after 1m0s
[05:18:37.811] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:86:0)
[05:18:37.811] Timer tick, asking Buildbucket for the build status
[05:18:37.898] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:37.898] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:87:0) after 1m0s
[05:19:37.928] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:87:0)
[05:19:37.928] Timer tick, asking Buildbucket for the build status
[05:19:38.007] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:38.007] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:88:0) after 1m0s
[05:20:38.121] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:88:0)
[05:20:38.121] Timer tick, asking Buildbucket for the build status
[05:20:38.194] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:38.194] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:89:0) after 1m0s
[05:21:38.277] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:89:0)
[05:21:38.277] Timer tick, asking Buildbucket for the build status
[05:21:38.352] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:38.353] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:90:0) after 1m0s
[05:22:38.376] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:90:0)
[05:22:38.376] Timer tick, asking Buildbucket for the build status
[05:22:38.522] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:38.522] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:91:0) after 1m0s
[05:23:38.561] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:91:0)
[05:23:38.561] Timer tick, asking Buildbucket for the build status
[05:23:38.643] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:38.643] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:92:0) after 1m0s
[05:24:39.265] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:92:0)
[05:24:39.265] Timer tick, asking Buildbucket for the build status
[05:24:39.381] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:39.381] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:93:0) after 1m0s
[05:25:39.698] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:93:0)
[05:25:39.698] Timer tick, asking Buildbucket for the build status
[05:25:41.619] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:41.619] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:94:0) after 1m0s
[05:26:41.796] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:94:0)
[05:26:41.796] Timer tick, asking Buildbucket for the build status
[05:26:42.269] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:42.269] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:95:0) after 1m0s
[05:27:42.516] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:95:0)
[05:27:42.516] Timer tick, asking Buildbucket for the build status
[05:27:42.665] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:42.665] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:96:0) after 1m0s
[05:28:42.714] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:96:0)
[05:28:42.714] Timer tick, asking Buildbucket for the build status
[05:28:42.809] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:42.809] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:97:0) after 1m0s
[05:29:42.915] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:97:0)
[05:29:42.915] Timer tick, asking Buildbucket for the build status
[05:29:43.024] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:43.024] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:98:0) after 1m0s
[05:30:43.166] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:98:0)
[05:30:43.166] Timer tick, asking Buildbucket for the build status
[05:30:43.249] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:43.249] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:99:0) after 1m0s
[05:31:43.374] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:99:0)
[05:31:43.374] Timer tick, asking Buildbucket for the build status
[05:31:43.446] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:43.446] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:100:0) after 1m0s
[05:32:43.450] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:100:0)
[05:32:43.450] Timer tick, asking Buildbucket for the build status
[05:32:43.522] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:43.522] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:101:0) after 1m0s
[05:33:43.541] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:101:0)
[05:33:43.541] Timer tick, asking Buildbucket for the build status
[05:33:43.641] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:43.641] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:102:0) after 1m0s
[05:34:43.738] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:102:0)
[05:34:43.738] Timer tick, asking Buildbucket for the build status
[05:34:43.835] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:43.835] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:103:0) after 1m0s
[05:35:43.964] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:103:0)
[05:35:43.964] Timer tick, asking Buildbucket for the build status
[05:35:44.204] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:44.204] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:104:0) after 1m0s
[05:36:44.259] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:104:0)
[05:36:44.259] Timer tick, asking Buildbucket for the build status
[05:36:44.353] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:44.353] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:105:0) after 1m0s
[05:37:44.463] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:105:0)
[05:37:44.463] Timer tick, asking Buildbucket for the build status
[05:37:44.558] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:44.558] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:106:0) after 1m0s
[05:38:44.666] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:106:0)
[05:38:44.666] Timer tick, asking Buildbucket for the build status
[05:38:44.763] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:44.763] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:107:0) after 1m0s
[05:39:44.833] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:107:0)
[05:39:44.834] Timer tick, asking Buildbucket for the build status
[05:39:44.968] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:44.968] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:108:0) after 1m0s
[05:40:45.081] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:108:0)
[05:40:45.081] Timer tick, asking Buildbucket for the build status
[05:40:45.182] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:45.182] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:109:0) after 1m0s
[05:41:45.354] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:109:0)
[05:41:45.354] Timer tick, asking Buildbucket for the build status
[05:41:45.449] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:45.449] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:110:0) after 1m0s
[05:42:45.486] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:110:0)
[05:42:45.486] Timer tick, asking Buildbucket for the build status
[05:42:45.563] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:45.563] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:111:0) after 1m0s
[05:43:45.635] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:111:0)
[05:43:45.635] Timer tick, asking Buildbucket for the build status
[05:43:45.828] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:45.828] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:112:0) after 1m0s
[05:44:45.893] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:112:0)
[05:44:45.893] Timer tick, asking Buildbucket for the build status
[05:44:45.989] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:45.989] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:113:0) after 1m0s
[05:45:46.009] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:113:0)
[05:45:46.009] Timer tick, asking Buildbucket for the build status
[05:45:46.088] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:46.088] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:114:0) after 1m0s
[05:46:46.148] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:114:0)
[05:46:46.148] Timer tick, asking Buildbucket for the build status
[05:46:46.241] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:46.241] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:115:0) after 1m0s
[05:47:46.441] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:115:0)
[05:47:46.441] Timer tick, asking Buildbucket for the build status
[05:47:46.609] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:46.609] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:116:0) after 1m0s
[05:48:46.794] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:116:0)
[05:48:46.794] Timer tick, asking Buildbucket for the build status
[05:48:46.887] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:46.887] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:117:0) after 1m0s
[05:49:46.968] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:117:0)
[05:49:46.968] Timer tick, asking Buildbucket for the build status
[05:49:47.146] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:47.146] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:118:0) after 1m0s
[05:50:47.146] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:118:0)
[05:50:47.146] Timer tick, asking Buildbucket for the build status
[05:50:47.232] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:47.232] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:119:0) after 1m0s
[05:51:47.367] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:119:0)
[05:51:47.367] Timer tick, asking Buildbucket for the build status
[05:51:48.004] Build 8852634526347085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:48.004] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018064588475876112:120:0) after 1m0s
[05:52:47.873] Received PubSub notification, asking Buildbucket for the build status
[05:52:48.012] Build 8852634526347085680: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:52:48.012] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1615873967165217",
"created_by": "project:chromium",
"created_ts": "1615866841899403",
"id": "8852634526347085680",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8933e0b9482364a01847a05ad57e0afb2f7679bc\"}}",
"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\": \"8933e0b9482364a01847a05ad57e0afb2f7679bc\", \"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-10-dcpw\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17812, \"clang_revision\": \"llvmorg-13-init-3462-gfe5c2c3c-2\", \"got_angle_revision\": \"687d3153197e5ef162e7bc75e931a09208ba3fe6\", \"got_dawn_revision\": \"8c40cad1c92d7e85154bbae4cb92539e2200d139\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"8933e0b9482364a01847a05ad57e0afb2f7679bc\", \"got_revision_cp\": \"refs/heads/master@{#863128}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"69b79eec6533f113920a5dd863ee2dea7d0c64e7\", \"got_v8_revision\": \"4e2fbf2b6d4fc2e9bbf239e146973a15bb3a0d40\", \"got_v8_revision_cp\": \"refs/heads/9.1.129@{#1}\", \"got_webrtc_revision\": \"6097b0fac0946a29d59a9266ea656c39b3fd7336\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33456}\", \"is_cached\": true, \"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\": \"8933e0b9482364a01847a05ad57e0afb2f7679bc\", \"swarm_hashes_refs/heads/master(at){#863128}_without_patch\": {\"absl_hardening_tests\": \"ff60933f24be52951ea45baa22a8270eb14b2143\", \"accessibility_unittests\": \"ad986ce72b5db882931148d514899d1f20272ff2\", \"angle_unittests\": \"8ac3853da2be95e354d9ab5f517e9d63d7597b33\", \"app_shell_unittests\": \"145961d922a30db9604cfec51c79a86903d4d080\", \"aura_unittests\": \"b48f7a77c964e0411ab1c95a8efbbca36cb188ab\", \"base_unittests\": \"0c9fe7ebfb872ad8789ffc4af1085adfdac365ce\", \"base_util_unittests\": \"03d58d3f927e35e260ab4ef2eea36fcc6fe6ecf8\", \"blink_common_unittests\": \"e458d40a4daea74ec7a821d7977ac48d17a1de0b\", \"blink_fuzzer_unittests\": \"384f37e9e513086dbeda7a8710dffea8673774af\", \"blink_heap_unittests\": \"00b364fc5df530121e853661ca2593790c80adc6\", \"blink_platform_unittests\": \"fdebcbfbccaceaadacca36165d3f0e1eb766fef8\", \"blink_unittests\": \"f7f9066f16ca66e8225b7afcde50afa76c107c25\", \"boringssl_crypto_tests\": \"e6d19b47f36dd6ac31be7e976e8b900e0019e90f\", \"boringssl_ssl_tests\": \"2c1c01f70551d36027bbe39461d78f657a05c5f9\", \"browser_switcher_bho_unittests\": \"b6fc30cb933de7bdf94949827ab9c5ab3c6eec70\", \"browser_tests\": \"2725d340618ea192d1116e1d76618f201aa055ec\", \"capture_unittests\": \"478dda59edb490ae2a9f96688b83988bb4a7df79\", \"cast_unittests\": \"152c3a491614111e49aa168a21198dc2f8564343\", \"cc_unittests\": \"b70fc3869ad98ccda5d86009ca33d8addc255d1e\", \"chrome_app_unittests\": \"de1c957a600266d45ec8de7c3783805780afe7b4\", \"chrome_cleaner_unittests\": \"a6fc3f385ad824ee2ac887e04a382870f0b1edda\", \"chrome_elf_unittests\": \"fea6ff4973a47651858d155225ec550d9b823dc3\", \"chromedriver_unittests\": \"d68f9ff3f1d51367084752a70b3d0d551e31a9b7\", \"color_unittests\": \"b383b15831c7870697a23187f62cf4ceef60fe60\", \"components_browsertests\": \"ed50bea6aa4f6475a62ca37b42c012a539771c48\", \"components_unittests\": \"5b696a06a19d9a978e2120144b9d3ec64219310f\", \"compositor_unittests\": \"adbe6d9f0b623b40c45fd88c0994961f188db303\", \"content_browsertests\": \"776351b833955571029dd4963e45cc88e8280467\", \"content_unittests\": \"1de8541f1808be365783a5f5cc565652a905acbd\", \"courgette_unittests\": \"0b0d7e7d17940d7924d74d26bab51d25e7d5d810\", \"crashpad_tests\": \"b42e7bd78ef62406e35cff32d6883d380701b353\", \"cronet_tests\": \"bccc9035c6be7f781e8b543e83459d11c27ba267\", \"cronet_unittests\": \"f6e387f673c7d81bd2ded9d617948db299e5f018\", \"crypto_unittests\": \"234a2ea754fc5d363a82eae14b9b92f31298d240\", \"delayloads_unittests\": \"89187bc0188305e98bce83409fc0c1d9c5178ebd\", \"device_unittests\": \"b25e9240d65c625f3b4ba5312de0f0cd7da2652f\", \"display_unittests\": \"af3ff5142ffc65d9012f68b0f88535fd7111575d\", \"elevation_service_unittests\": \"5ba5bb1952735fd472c1337c374609ba14844736\", \"events_unittests\": \"cf1c24a010da83e63f59a14091425eef95f3cc01\", \"extensions_browsertests\": \"3c507ce3882822bf78f6fbf3d418f3693e895849\", \"extensions_unittests\": \"b1f6d9291e09e1cf452696da52d209af5f2396f5\", \"filesystem_service_unittests\": \"e110493ab95babce0129b7f7ae99f46cd5840552\", \"gcm_unit_tests\": \"c1d60135274c916b8d5b61d1cebe9723bfce43fd\", \"gcp_unittests\": \"218a647b4ef106928e4859580ecda34f1ef709bc\", \"gfx_unittests\": \"3688e194da035dc09b6dcc8ee4347e8b543e35ff\", \"gin_unittests\": \"ff6c79a96ec98651422e15529c0fd9913d14e608\", \"google_apis_unittests\": \"f1acb9cc14c9284395dccc7e070e65f9f1dfe438\", \"gpu_unittests\": \"9ab912d8bcd897e86b9897ddf073f3f4ea0001c1\", \"gwp_asan_unittests\": \"67078447814790afc812dcb1d709d9fdd7c0b11d\", \"headless_browsertests\": \"5b7350d07e14f93fdffeaea08663e78385dff5f8\", \"headless_unittests\": \"14e3f34f696b51ffa8dabb7250dd49bb9c94f801\", \"install_static_unittests\": \"56b2ce6134cabd032c9ff64a66b785c6de521b22\", \"installer_util_unittests\": \"5d0b54c04d5db70723678856f6901cf4d241ee75\", \"interactive_ui_tests\": \"51048d628411bd58deed59dc22d0ef0aa539e560\", \"ipc_tests\": \"b62520d919d0abfa8d9131a9b6e662b5e966469b\", \"jingle_unittests\": \"4a0e9a48b7d7c815e3e3591d6902420b3d37b0b5\", \"latency_unittests\": \"f8c57820dc3e11c68db751bb42167760315efe72\", \"libjingle_xmpp_unittests\": \"b8ec9f7de8a2f6a27f9aad6d000084f8729a7292\", \"liburlpattern_unittests\": \"3645f0ef05bc7ad2eff3192240160a4789553ceb\", \"media_blink_unittests\": \"cf0aae47e741414f2209d524c98d8346d9242981\", \"media_unittests\": \"3198226f232bde1d1c27a2108da1ff1b247d70c1\", \"message_center_unittests\": \"3d965031bc16a82cb1002d7808146fc9c2e4381b\", \"midi_unittests\": \"8a6c0d302d360b8217932387dda7d5dbc76afcef\", \"mojo_core_unittests\": \"32d6b16c8a36f22110e6915b7c328b462aa8e839\", \"mojo_unittests\": \"2823b221db5aef8017162f38130dd70fa44f8dc5\", \"nacl_loader_unittests\": \"dd6f367b49a381e99a03abff0fed80c12a2e48f5\", \"native_theme_unittests\": \"a5c39c1f91b0b9dde3f5d532bd9cb04072bb972d\", \"net_unittests\": \"7925484030f336b8d6c80f08de349df6eeacd3a3\", \"notification_helper_unittests\": \"df1f25529036c22a52eaefa96c7368969fdb5505\", \"pdf_unittests\": \"099618d5c1514d610688ec68deed91673bb7d9b6\", \"perfetto_unittests\": \"82b46f9bc59a616c9d42cca5379bf5fe9faa7bfa\", \"ppapi_unittests\": \"6469fe0e47f0442e685f695a7847ab1905f747a5\", \"printing_unittests\": \"2230f16fd345b71d6755bd6b90776ecf168938de\", \"remoting_unittests\": \"6e972c00ba737d1ce25d38ad4d9d8ca9388d39ef\", \"sbox_integration_tests\": \"752a40805ed169c1ea84237b71b315e809551d90\", \"sbox_unittests\": \"ce026b4dc0f3c417bac3e95eb43d5225589b2df4\", \"sbox_validation_tests\": \"f106ea9983854508c6c36e79a8adddefd03f96cc\", \"service_manager_unittests\": \"6c525d2ffa5bdfaa09bcb203b3b6d8aa930ca660\", \"services_unittests\": \"6a66993218e4e9dbafdaecad23fc3380335e3434\", \"setup_unittests\": \"3ecf5267e614961d6dd7eaeb248675a5e77d7124\", \"shell_dialogs_unittests\": \"8ff7663195083f42e1ed71faaf2f7c7c4ef18eab\", \"skia_unittests\": \"43169242f2fae9eb772e33422597e055797f6035\", \"snapshot_unittests\": \"ec85b436ebc8d0fb186b24ec20aee39090bd54af\", \"sql_unittests\": \"31cacd548c9a101d1ed3faec7d9fa450df657fdf\", \"storage_unittests\": \"68138f4e072b2fac366bc85f85ba900946d3d48c\", \"sync_integration_tests\": \"9996914c64f672fbb411dadaa26be0dbf54a7cd6\", \"traffic_annotation_auditor_unittests\": \"8a3e85ebe62308c1bb9c7d9ea1ede230c070cd60\", \"ui_base_unittests\": \"472e4801b76c24b1e379ecb811f0282db37cea73\", \"ui_touch_selection_unittests\": \"402936664dac738f4490adda90c2488ea1ebecab\", \"unit_tests\": \"19e33ea433729b938fb4246e3ed70f8b6e0e55a8\", \"updater_tests\": \"9540d1440342d1c3321b6e63710569c5c25a0b73\", \"url_unittests\": \"7da7520d37721e29e15961a70295c2dbdfd01d93\", \"views_unittests\": \"952cce888012d36c2999669bd1a43a3a464ba7a3\", \"viz_unittests\": \"9c3860d75e3f2c2fc090596fe09f1a777c090275\", \"vr_common_unittests\": \"f54b1d2c1283a27e8c95071c0de5a72f15b9e371\", \"vr_pixeltests\": \"312aaab8def992acc22a707415f2ebee784b921a\", \"weblayer_browsertests\": \"963239c084f61cfe08b3e0c0ef06f190a7650352\", \"weblayer_unittests\": \"9b29a61b40e017007b69c34b651e77380706abca\", \"wm_unittests\": \"6b74871e92c4191529140d3281673aebfa90c2f8\", \"wtf_unittests\": \"50e90d0b897ac21704ede381b60748c38c0ca39c\", \"zlib_unittests\": \"e0526d7b68e242cc47f7508b2c3baf4645f295c5\", \"zucchini_unittests\": \"4e9dac098015cd5cc454b62a59e6aadf139c4c54\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-10-dcpw\"], \"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\": [\"5754-8c4f760\"], \"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": "1615867022714936",
"status": "COMPLETED",
"status_changed_ts": "1615873967443307",
"tags": [
"build_address:luci.chromium.ci/win-asan/17812",
"builder:win-asan",
"buildset:commit/git/8933e0b9482364a01847a05ad57e0afb2f7679bc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8933e0b9482364a01847a05ad57e0afb2f7679bc",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018064588475876112",
"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/8852634526347085680/+/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:5251fcd5ff185110",
"user_agent:luci-scheduler"
],
"updated_ts": "1615873967447332",
"url": "https://ci.chromium.org/b/8852634526347085680",
"utcnow_ts": "1615873968003857"
}
}
[05:52:48.012] Invocation finished in 1h58m47.527393183s with status SUCCEEDED