[22:43:22.703] New invocation is queued and will start shortly
[22:43:24.694] Starting the invocation (attempt 1)
[22:43:24.742] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:43:24.742] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:43:24.742] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9030405319058983088",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"16ccdc07d59cc5e007c594e593ac0436e1c3d399\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9030405319058983088",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/16ccdc07d59cc5e007c594e593ac0436e1c3d399",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/16ccdc07d59cc5e007c594e593ac0436e1c3d399",
"gitiles_ref:refs/heads/master"
]
}
[22:43:25.466] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1604097804879805",
"id": "8864975256109706464",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"16ccdc07d59cc5e007c594e593ac0436e1c3d399\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1604097805337588",
"tags": [
"build_address:luci.chromium.ci/win-asan/15374",
"builder:win-asan",
"buildset:commit/git/16ccdc07d59cc5e007c594e593ac0436e1c3d399",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/16ccdc07d59cc5e007c594e593ac0436e1c3d399",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030405319058983088",
"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/8864975256109706464/+/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": "1604097805337761",
"url": "https://ci.chromium.org/b/8864975256109706464",
"utcnow_ts": "1604097805456266"
}
}
[22:43:25.466] Task URL: https://ci.chromium.org/b/8864975256109706464
[22:43:25.466] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:2:0) after 1m0s
[22:44:25.465] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:2:0)
[22:44:25.465] Timer tick, asking Buildbucket for the build status
[22:44:25.946] Build 8864975256109706464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:44:25.946] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:3:0) after 1m0s
[22:45:25.947] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:3:0)
[22:45:25.947] Timer tick, asking Buildbucket for the build status
[22:45:26.046] Build 8864975256109706464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:45:26.046] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:4:0) after 1m0s
[22:46:26.089] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:4:0)
[22:46:26.089] Timer tick, asking Buildbucket for the build status
[22:46:26.166] Build 8864975256109706464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:46:26.166] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:5:0) after 1m0s
[22:47:23.315] Received PubSub notification, asking Buildbucket for the build status
[22:47:23.406] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:26.189] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:5:0)
[22:47:26.189] Timer tick, asking Buildbucket for the build status
[22:47:26.301] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:26.301] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:7:0) after 1m0s
[22:48:26.337] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:7:0)
[22:48:26.337] Timer tick, asking Buildbucket for the build status
[22:48:26.432] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:26.432] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:8:0) after 1m0s
[22:49:26.454] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:8:0)
[22:49:26.454] Timer tick, asking Buildbucket for the build status
[22:49:26.567] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:26.567] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:9:0) after 1m0s
[22:50:26.622] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:9:0)
[22:50:26.622] Timer tick, asking Buildbucket for the build status
[22:50:26.677] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:26.677] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:10:0) after 1m0s
[22:51:26.783] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:10:0)
[22:51:26.783] Timer tick, asking Buildbucket for the build status
[22:51:26.841] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:26.841] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:11:0) after 1m0s
[22:52:26.862] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:11:0)
[22:52:26.862] Timer tick, asking Buildbucket for the build status
[22:52:26.982] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:26.982] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:12:0) after 1m0s
[22:53:27.005] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:12:0)
[22:53:27.005] Timer tick, asking Buildbucket for the build status
[22:53:27.137] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:27.137] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:13:0) after 1m0s
[22:54:27.181] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:13:0)
[22:54:27.181] Timer tick, asking Buildbucket for the build status
[22:54:27.580] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:27.580] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:14:0) after 1m0s
[22:55:27.646] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:14:0)
[22:55:27.646] Timer tick, asking Buildbucket for the build status
[22:55:27.773] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:27.773] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:15:0) after 1m0s
[22:56:27.814] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:15:0)
[22:56:27.814] Timer tick, asking Buildbucket for the build status
[22:56:27.905] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:27.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:16:0) after 1m0s
[22:57:27.933] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:16:0)
[22:57:27.933] Timer tick, asking Buildbucket for the build status
[22:57:28.089] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:28.089] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:17:0) after 1m0s
[22:58:28.145] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:17:0)
[22:58:28.145] Timer tick, asking Buildbucket for the build status
[22:58:28.322] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:28.322] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:18:0) after 1m0s
[22:59:28.316] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:18:0)
[22:59:28.316] Timer tick, asking Buildbucket for the build status
[22:59:28.414] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:28.414] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:19:0) after 1m0s
[23:00:28.467] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:19:0)
[23:00:28.467] Timer tick, asking Buildbucket for the build status
[23:00:29.310] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:29.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:20:0) after 1m0s
[23:01:29.373] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:20:0)
[23:01:29.373] Timer tick, asking Buildbucket for the build status
[23:01:29.512] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:29.512] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:21:0) after 1m0s
[23:02:29.513] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:21:0)
[23:02:29.513] Timer tick, asking Buildbucket for the build status
[23:02:29.588] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:29.588] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:22:0) after 1m0s
[23:03:29.599] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:22:0)
[23:03:29.599] Timer tick, asking Buildbucket for the build status
[23:03:29.742] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:29.742] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:23:0) after 1m0s
[23:04:29.769] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:23:0)
[23:04:29.769] Timer tick, asking Buildbucket for the build status
[23:04:30.033] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:30.033] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:24:0) after 1m0s
[23:05:30.109] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:24:0)
[23:05:30.109] Timer tick, asking Buildbucket for the build status
[23:05:30.291] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:30.291] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:25:0) after 1m0s
[23:06:30.342] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:25:0)
[23:06:30.342] Timer tick, asking Buildbucket for the build status
[23:06:30.410] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:30.410] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:26:0) after 1m0s
[23:07:30.460] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:26:0)
[23:07:30.460] Timer tick, asking Buildbucket for the build status
[23:07:30.672] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:30.672] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:27:0) after 1m0s
[23:08:30.752] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:27:0)
[23:08:30.752] Timer tick, asking Buildbucket for the build status
[23:08:30.864] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:30.864] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:28:0) after 1m0s
[23:09:30.906] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:28:0)
[23:09:30.906] Timer tick, asking Buildbucket for the build status
[23:09:31.002] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:31.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:29:0) after 1m0s
[23:10:31.049] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:29:0)
[23:10:31.049] Timer tick, asking Buildbucket for the build status
[23:10:31.131] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:31.131] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:30:0) after 1m0s
[23:11:31.176] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:30:0)
[23:11:31.176] Timer tick, asking Buildbucket for the build status
[23:11:31.247] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:31.247] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:31:0) after 1m0s
[23:12:31.271] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:31:0)
[23:12:31.271] Timer tick, asking Buildbucket for the build status
[23:12:31.692] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:31.692] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:32:0) after 1m0s
[23:13:31.771] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:32:0)
[23:13:31.771] Timer tick, asking Buildbucket for the build status
[23:13:31.866] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:31.866] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:33:0) after 1m0s
[23:14:31.983] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:33:0)
[23:14:31.983] Timer tick, asking Buildbucket for the build status
[23:14:32.127] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:32.127] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:34:0) after 1m0s
[23:15:32.147] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:34:0)
[23:15:32.147] Timer tick, asking Buildbucket for the build status
[23:15:32.214] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:32.215] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:35:0) after 1m0s
[23:16:32.276] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:35:0)
[23:16:32.276] Timer tick, asking Buildbucket for the build status
[23:16:32.397] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:32.397] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:36:0) after 1m0s
[23:17:32.417] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:36:0)
[23:17:32.417] Timer tick, asking Buildbucket for the build status
[23:17:32.537] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:32.537] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:37:0) after 1m0s
[23:18:32.608] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:37:0)
[23:18:32.608] Timer tick, asking Buildbucket for the build status
[23:18:32.737] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:32.737] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:38:0) after 1m0s
[23:19:32.814] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:38:0)
[23:19:32.814] Timer tick, asking Buildbucket for the build status
[23:19:32.881] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:32.881] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:39:0) after 1m0s
[23:20:32.905] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:39:0)
[23:20:32.905] Timer tick, asking Buildbucket for the build status
[23:20:33.027] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:33.027] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:40:0) after 1m0s
[23:21:33.169] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:40:0)
[23:21:33.169] Timer tick, asking Buildbucket for the build status
[23:21:33.236] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:33.236] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:41:0) after 1m0s
[23:22:33.244] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:41:0)
[23:22:33.244] Timer tick, asking Buildbucket for the build status
[23:22:33.485] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:33.485] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:42:0) after 1m0s
[23:23:33.536] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:42:0)
[23:23:33.536] Timer tick, asking Buildbucket for the build status
[23:23:33.838] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:33.838] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:43:0) after 1m0s
[23:24:33.953] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:43:0)
[23:24:33.953] Timer tick, asking Buildbucket for the build status
[23:24:34.140] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:34.140] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:44:0) after 1m0s
[23:25:34.181] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:44:0)
[23:25:34.181] Timer tick, asking Buildbucket for the build status
[23:25:34.252] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:34.252] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:45:0) after 1m0s
[23:26:34.374] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:45:0)
[23:26:34.374] Timer tick, asking Buildbucket for the build status
[23:26:34.441] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:34.441] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:46:0) after 1m0s
[23:27:34.489] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:46:0)
[23:27:34.489] Timer tick, asking Buildbucket for the build status
[23:27:34.749] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:34.749] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:47:0) after 1m0s
[23:28:34.784] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:47:0)
[23:28:34.785] Timer tick, asking Buildbucket for the build status
[23:28:35.052] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:35.053] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:48:0) after 1m0s
[23:29:35.089] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:48:0)
[23:29:35.089] Timer tick, asking Buildbucket for the build status
[23:29:35.283] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:35.283] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:49:0) after 1m0s
[23:30:35.334] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:49:0)
[23:30:35.334] Timer tick, asking Buildbucket for the build status
[23:30:35.559] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:35.559] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:50:0) after 1m0s
[23:31:35.611] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:50:0)
[23:31:35.611] Timer tick, asking Buildbucket for the build status
[23:31:35.690] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:35.690] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:51:0) after 1m0s
[23:32:35.711] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:51:0)
[23:32:35.711] Timer tick, asking Buildbucket for the build status
[23:32:35.877] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:35.877] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:52:0) after 1m0s
[23:33:35.899] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:52:0)
[23:33:35.899] Timer tick, asking Buildbucket for the build status
[23:33:36.022] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:36.022] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:53:0) after 1m0s
[23:34:36.082] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:53:0)
[23:34:36.082] Timer tick, asking Buildbucket for the build status
[23:34:36.223] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:36.223] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:54:0) after 1m0s
[23:35:36.223] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:54:0)
[23:35:36.223] Timer tick, asking Buildbucket for the build status
[23:35:36.339] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:36.339] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:55:0) after 1m0s
[23:36:36.400] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:55:0)
[23:36:36.400] Timer tick, asking Buildbucket for the build status
[23:36:36.510] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:36.510] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:56:0) after 1m0s
[23:37:36.604] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:56:0)
[23:37:36.604] Timer tick, asking Buildbucket for the build status
[23:37:36.684] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:36.684] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:57:0) after 1m0s
[23:38:36.693] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:57:0)
[23:38:36.693] Timer tick, asking Buildbucket for the build status
[23:38:37.333] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:37.333] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:58:0) after 1m0s
[23:39:37.460] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:58:0)
[23:39:37.460] Timer tick, asking Buildbucket for the build status
[23:39:37.535] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:37.535] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:59:0) after 1m0s
[23:40:37.561] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:59:0)
[23:40:37.561] Timer tick, asking Buildbucket for the build status
[23:40:37.725] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:37.725] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:60:0) after 1m0s
[23:41:37.793] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:60:0)
[23:41:37.793] Timer tick, asking Buildbucket for the build status
[23:41:37.900] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:37.900] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:61:0) after 1m0s
[23:42:37.928] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:61:0)
[23:42:37.928] Timer tick, asking Buildbucket for the build status
[23:42:38.049] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:38.049] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:62:0) after 1m0s
[23:43:38.072] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:62:0)
[23:43:38.072] Timer tick, asking Buildbucket for the build status
[23:43:38.197] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:38.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:63:0) after 1m0s
[23:44:38.222] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:63:0)
[23:44:38.222] Timer tick, asking Buildbucket for the build status
[23:44:38.388] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:38.388] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:64:0) after 1m0s
[23:45:38.730] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:64:0)
[23:45:38.730] Timer tick, asking Buildbucket for the build status
[23:45:38.882] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:38.882] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:65:0) after 1m0s
[23:46:39.205] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:65:0)
[23:46:39.205] Timer tick, asking Buildbucket for the build status
[23:46:39.521] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:39.521] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:66:0) after 1m0s
[23:47:39.641] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:66:0)
[23:47:39.641] Timer tick, asking Buildbucket for the build status
[23:47:39.992] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:39.992] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:67:0) after 1m0s
[23:48:40.169] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:67:0)
[23:48:40.169] Timer tick, asking Buildbucket for the build status
[23:48:40.280] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:40.280] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:68:0) after 1m0s
[23:49:40.360] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:68:0)
[23:49:40.360] Timer tick, asking Buildbucket for the build status
[23:49:40.526] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:40.526] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:69:0) after 1m0s
[23:50:40.657] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:69:0)
[23:50:40.657] Timer tick, asking Buildbucket for the build status
[23:50:43.590] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:43.590] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:70:0) after 1m0s
[23:51:43.691] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:70:0)
[23:51:43.691] Timer tick, asking Buildbucket for the build status
[23:51:43.981] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:43.981] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:71:0) after 1m0s
[23:52:44.043] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:71:0)
[23:52:44.043] Timer tick, asking Buildbucket for the build status
[23:52:44.297] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:44.297] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:72:0) after 1m0s
[23:53:44.306] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:72:0)
[23:53:44.306] Timer tick, asking Buildbucket for the build status
[23:53:44.434] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:44.435] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:73:0) after 1m0s
[23:54:44.425] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:73:0)
[23:54:44.425] Timer tick, asking Buildbucket for the build status
[23:54:44.506] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:44.506] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:74:0) after 1m0s
[23:55:44.592] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:74:0)
[23:55:44.592] Timer tick, asking Buildbucket for the build status
[23:55:44.669] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:44.669] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:75:0) after 1m0s
[23:56:44.680] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:75:0)
[23:56:44.680] Timer tick, asking Buildbucket for the build status
[23:56:44.756] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:44.756] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:76:0) after 1m0s
[23:57:44.769] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:76:0)
[23:57:44.769] Timer tick, asking Buildbucket for the build status
[23:57:44.925] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:44.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:77:0) after 1m0s
[23:58:44.989] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:77:0)
[23:58:44.989] Timer tick, asking Buildbucket for the build status
[23:58:45.124] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:45.124] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:78:0) after 1m0s
[23:59:45.171] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:78:0)
[23:59:45.171] Timer tick, asking Buildbucket for the build status
[23:59:45.232] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:45.232] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:79:0) after 1m0s
[00:00:45.274] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:79:0)
[00:00:45.274] Timer tick, asking Buildbucket for the build status
[00:00:45.373] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:45.373] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:80:0) after 1m0s
[00:01:45.431] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:80:0)
[00:01:45.431] Timer tick, asking Buildbucket for the build status
[00:01:45.508] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:45.508] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:81:0) after 1m0s
[00:02:45.710] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:81:0)
[00:02:45.710] Timer tick, asking Buildbucket for the build status
[00:02:45.853] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:45.853] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:82:0) after 1m0s
[00:03:45.888] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:82:0)
[00:03:45.888] Timer tick, asking Buildbucket for the build status
[00:03:46.209] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:46.209] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:83:0) after 1m0s
[00:04:46.250] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:83:0)
[00:04:46.250] Timer tick, asking Buildbucket for the build status
[00:04:46.515] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:46.516] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:84:0) after 1m0s
[00:05:46.570] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:84:0)
[00:05:46.570] Timer tick, asking Buildbucket for the build status
[00:05:46.752] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:46.752] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:85:0) after 1m0s
[00:06:46.889] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:85:0)
[00:06:46.889] Timer tick, asking Buildbucket for the build status
[00:06:47.280] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:47.280] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:86:0) after 1m0s
[00:07:47.385] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:86:0)
[00:07:47.385] Timer tick, asking Buildbucket for the build status
[00:07:48.137] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:48.137] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:87:0) after 1m0s
[00:08:48.147] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:87:0)
[00:08:48.147] Timer tick, asking Buildbucket for the build status
[00:08:48.267] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:48.267] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:88:0) after 1m0s
[00:09:48.272] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:88:0)
[00:09:48.272] Timer tick, asking Buildbucket for the build status
[00:09:48.401] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:48.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:89:0) after 1m0s
[00:10:48.484] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:89:0)
[00:10:48.484] Timer tick, asking Buildbucket for the build status
[00:10:49.248] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:49.248] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:90:0) after 1m0s
[00:11:49.342] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:90:0)
[00:11:49.342] Timer tick, asking Buildbucket for the build status
[00:11:49.895] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:49.895] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:91:0) after 1m0s
[00:12:49.986] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:91:0)
[00:12:49.987] Timer tick, asking Buildbucket for the build status
[00:12:50.219] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:50.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:92:0) after 1m0s
[00:13:50.248] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:92:0)
[00:13:50.248] Timer tick, asking Buildbucket for the build status
[00:13:50.366] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:50.366] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:93:0) after 1m0s
[00:14:50.408] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:93:0)
[00:14:50.408] Timer tick, asking Buildbucket for the build status
[00:14:50.968] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:50.968] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:94:0) after 1m0s
[00:15:51.057] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:94:0)
[00:15:51.057] Timer tick, asking Buildbucket for the build status
[00:15:51.146] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:51.146] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:95:0) after 1m0s
[00:16:51.246] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:95:0)
[00:16:51.246] Timer tick, asking Buildbucket for the build status
[00:16:51.344] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:51.344] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:96:0) after 1m0s
[00:17:51.482] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:96:0)
[00:17:51.482] Timer tick, asking Buildbucket for the build status
[00:17:51.556] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:51.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:97:0) after 1m0s
[00:18:51.655] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:97:0)
[00:18:51.655] Timer tick, asking Buildbucket for the build status
[00:18:52.699] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:52.699] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:98:0) after 1m0s
[00:19:52.822] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:98:0)
[00:19:52.822] Timer tick, asking Buildbucket for the build status
[00:19:53.159] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:53.159] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:99:0) after 1m0s
[00:20:53.355] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:99:0)
[00:20:53.355] Timer tick, asking Buildbucket for the build status
[00:20:53.445] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:53.445] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:100:0) after 1m0s
[00:21:53.687] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:100:0)
[00:21:53.687] Timer tick, asking Buildbucket for the build status
[00:21:53.806] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:53.806] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:101:0) after 1m0s
[00:22:53.875] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:101:0)
[00:22:53.875] Timer tick, asking Buildbucket for the build status
[00:22:53.970] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:53.970] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:102:0) after 1m0s
[00:23:53.989] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:102:0)
[00:23:53.989] Timer tick, asking Buildbucket for the build status
[00:23:54.125] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:54.125] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:103:0) after 1m0s
[00:24:54.278] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:103:0)
[00:24:54.278] Timer tick, asking Buildbucket for the build status
[00:24:54.354] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:54.354] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:104:0) after 1m0s
[00:25:54.536] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:104:0)
[00:25:54.536] Timer tick, asking Buildbucket for the build status
[00:25:54.603] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:54.603] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:105:0) after 1m0s
[00:26:54.653] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:105:0)
[00:26:54.653] Timer tick, asking Buildbucket for the build status
[00:26:54.817] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:54.817] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:106:0) after 1m0s
[00:27:54.932] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:106:0)
[00:27:54.932] Timer tick, asking Buildbucket for the build status
[00:27:55.821] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:55.821] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:107:0) after 1m0s
[00:28:55.979] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:107:0)
[00:28:55.979] Timer tick, asking Buildbucket for the build status
[00:28:56.105] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:56.105] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:108:0) after 1m0s
[00:29:56.089] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:108:0)
[00:29:56.089] Timer tick, asking Buildbucket for the build status
[00:29:56.949] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:56.949] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:109:0) after 1m0s
[00:30:57.061] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:109:0)
[00:30:57.062] Timer tick, asking Buildbucket for the build status
[00:30:57.181] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:57.181] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:110:0) after 1m0s
[00:31:57.263] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:110:0)
[00:31:57.263] Timer tick, asking Buildbucket for the build status
[00:31:57.378] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:57.378] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:111:0) after 1m0s
[00:32:57.434] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:111:0)
[00:32:57.434] Timer tick, asking Buildbucket for the build status
[00:32:57.755] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:57.755] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:112:0) after 1m0s
[00:33:57.788] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:112:0)
[00:33:57.788] Timer tick, asking Buildbucket for the build status
[00:33:57.929] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:57.929] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:113:0) after 1m0s
[00:34:58.045] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:113:0)
[00:34:58.045] Timer tick, asking Buildbucket for the build status
[00:34:58.486] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:58.486] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:114:0) after 1m0s
[00:35:58.631] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:114:0)
[00:35:58.631] Timer tick, asking Buildbucket for the build status
[00:35:58.710] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:58.710] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:115:0) after 1m0s
[00:36:58.703] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:115:0)
[00:36:58.703] Timer tick, asking Buildbucket for the build status
[00:36:58.767] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:58.767] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:116:0) after 1m0s
[00:37:58.816] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:116:0)
[00:37:58.816] Timer tick, asking Buildbucket for the build status
[00:37:58.891] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:58.891] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:117:0) after 1m0s
[00:38:58.948] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:117:0)
[00:38:58.948] Timer tick, asking Buildbucket for the build status
[00:38:59.066] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:59.066] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:118:0) after 1m0s
[00:39:59.125] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:118:0)
[00:39:59.125] Timer tick, asking Buildbucket for the build status
[00:39:59.201] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:59.201] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:119:0) after 1m0s
[00:40:59.241] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:119:0)
[00:40:59.241] Timer tick, asking Buildbucket for the build status
[00:40:59.334] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:59.334] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:120:0) after 1m0s
[00:41:59.379] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:120:0)
[00:41:59.379] Timer tick, asking Buildbucket for the build status
[00:41:59.518] Build 8864975256109706464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:59.518] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030405319058983088:121:0) after 1m0s
[00:42:26.950] Received PubSub notification, asking Buildbucket for the build status
[00:42:27.031] Build 8864975256109706464: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[00:42:27.031] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1604104946295572",
"created_by": "project:chromium",
"created_ts": "1604097804879805",
"failure_reason": "BUILD_FAILURE",
"id": "8864975256109706464",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"16ccdc07d59cc5e007c594e593ac0436e1c3d399\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**gcp_unittests** failed because of:\\n\\n- All/AssociatedUserValidatorUserAccessBlockingTest.BlockUserAccessAsNeeded/21519\\n\\n- All/AssociatedUserValidatorUserAccessBlockingTest.BlockUserAccessAsNeeded/21518\\n\\n- All/AssociatedUserValidatorUserAccessBlockingTest.BlockUserAccessAsNeeded/21515\\n\\n- All/AssociatedUserValidatorUserAccessBlockingTest.BlockUserAccessAsNeeded/21514\\n\\n- ...27471 more failure(s) (27475 total)...\"}, \"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\": \"16ccdc07d59cc5e007c594e593ac0436e1c3d399\", \"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-9-6o02\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15374, \"clang_revision\": \"llvmorg-12-init-5627-gf086e85e-2\", \"got_angle_revision\": \"42ad027982404039e6c9a921b2f6354f55b65a2e\", \"got_dawn_revision\": \"5a1d786754ad865c1b72c46e4f9c0d3dcc19aa72\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"16ccdc07d59cc5e007c594e593ac0436e1c3d399\", \"got_revision_cp\": \"refs/heads/master@{#822847}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"e0de282e678b72dac25b81f8d25305231aec01f8\", \"got_v8_revision\": \"96789f0aa67d93ea125191724666dab1be5a639c\", \"got_v8_revision_cp\": \"refs/heads/8.8.213@{#1}\", \"got_webrtc_revision\": \"ee24eb66d23ffea5ef729dc340e1fc4381c72a4a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32507}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"16ccdc07d59cc5e007c594e593ac0436e1c3d399\", \"swarm_hashes_refs/heads/master(at){#822847}_without_patch\": {\"absl_hardening_tests\": \"01e7b0c6cbf44971a94a56a790dd5a1495b26a1c\", \"accessibility_unittests\": \"7598b0b90a620d3e7fe0842ef9aa21073e0482fc\", \"angle_unittests\": \"9423484c04456d80fb780d8c89cb72de200cdb21\", \"app_shell_unittests\": \"5341394798326e3e948bf9d7f8d8061892dc74e7\", \"aura_unittests\": \"d7b2ce1c3859aaf5ea30acf5ae7ee08f986ea4ac\", \"base_unittests\": \"3f07821faaef897191ba3fa1630532d4aaee4660\", \"base_util_unittests\": \"cb5cf900b82add9cee105ccfcbd18c01dd7553dd\", \"blink_common_unittests\": \"92bbea718181ce8c7b9716c26c65d7ba8afc5434\", \"blink_fuzzer_unittests\": \"2d3111dc6e8ca5eccc84a6a6444547ca7e1d1234\", \"blink_heap_unittests\": \"031dd9a07ed168c8b4d318fc825eb2c50f039d5d\", \"blink_platform_unittests\": \"f2d494014b76fadaf8448073a89b8c9682628006\", \"blink_unittests\": \"45a2ba0c2d99932f408802bd79ae89ee0089e222\", \"boringssl_crypto_tests\": \"2348c1b6ab341d7cb8e92efcd8a4ccb293e24a66\", \"boringssl_ssl_tests\": \"65e241ec1f148435d339cc45cb1b738e4dd712ce\", \"browser_switcher_bho_unittests\": \"647da7a60f880381389fe547a16c364529546e75\", \"browser_tests\": \"ea33ab00b128a41f2a7d555bbb86618fdf202988\", \"capture_unittests\": \"4764f65098ae8fe47800dec0148df35525ac30d1\", \"cast_unittests\": \"32d77ed625d46bf66a388308b9a83bfd19fa2d6f\", \"cc_unittests\": \"efe244c84c5ec2f6ebbdf52fbb7b0914ad059550\", \"chrome_app_unittests\": \"57493563cbdee3ce12f5d576c6831071080e0e64\", \"chrome_cleaner_unittests\": \"e9bea3deec3ed52396ef33de5a1d633c7e4ba91a\", \"chrome_elf_unittests\": \"29df619ecfe6e58bd09942e9464e03b46388bb44\", \"chromedriver_unittests\": \"4b866c524403be1ec4a19ef6ff8020cf0332fd1a\", \"components_browsertests\": \"ffe47c317cca77f74a6f6df59f3cf54b305eca22\", \"components_unittests\": \"47e7327339f0f439cdd7808928cfcab7ca90302a\", \"compositor_unittests\": \"6a08114b38517e0ab591bac1d0029023ea8120bf\", \"content_browsertests\": \"692d5b71a3f8c4a5c976f8ebcf7104d394f56ab7\", \"content_unittests\": \"ef26e1e3cffbab782ddb0774e9240948e34dce59\", \"courgette_unittests\": \"b2df27ed4fcb1b397c409a142aefb6b18cb6aa5e\", \"crashpad_tests\": \"0442e223d2f654cec21d2e2b1ece55e90e6e4612\", \"cronet_tests\": \"5c887f7510c1bde7b502642441ca9be8a7077aae\", \"cronet_unittests\": \"edf819db0028868502a50c1b122e1d47feae3453\", \"crypto_unittests\": \"b71577f2c8ed0c79fc611e26476a27462093781e\", \"delayloads_unittests\": \"71648da6ce540bd9c08e412de58acef25a43bd33\", \"device_unittests\": \"7834660893cbb7339bd4d58c61137f7174233c5a\", \"display_unittests\": \"c013806c6873789e095a0e86bd3557cab70f4186\", \"elevation_service_unittests\": \"fd03de0dd1e0cabf7d2c03adc52796b638d7b412\", \"events_unittests\": \"2e8f71630b01de9f13e79347e64f3d0b8a3ac6c4\", \"extensions_browsertests\": \"2610c639888fd871bf8a9ea521e09289cc0293a3\", \"extensions_unittests\": \"8b983de972a770570f9270bbf1aa4f21559b3caa\", \"filesystem_service_unittests\": \"3548da5175979e5f1cf61ec89a2b7509271aeeab\", \"gcm_unit_tests\": \"9cc98f81a47450459e77d77deb98905e0a64ebe9\", \"gcp_unittests\": \"f3587b7183b0af90a54511bfc68a832a709f8766\", \"gfx_unittests\": \"4fb01481671de618d2666f30e19fb33f39bb1aab\", \"gin_unittests\": \"f28f50e79d1c57736fb9a06700aaf304565cbc29\", \"google_apis_unittests\": \"897d24d100dfc2f9d73c3dffc47008a267ad8f19\", \"gpu_unittests\": \"508b0058c6f20abd984832ba2cc5a91279d54235\", \"gwp_asan_unittests\": \"cc6d241b7d9baaad235a388073f2b27017a05c33\", \"headless_browsertests\": \"0c80cd830b0090d4ef0cad12eba5709ad169d5eb\", \"headless_unittests\": \"7a4f43d966663bf6c8f4f191dea90c938a75b5dc\", \"install_static_unittests\": \"20f9e4f41318a00f56a2d5fc61143d90b238c193\", \"installer_util_unittests\": \"15e6c6249ba7b8beaf34005bf3e2cbaede60ee2a\", \"interactive_ui_tests\": \"7d771079236783739ceef23dc40aece7640668c8\", \"ipc_tests\": \"17a5ae21dff546162d9ca69bf9d0bd0885f4fe0b\", \"jingle_unittests\": \"3d649a55b0e97a266312f8004e02220d4826d771\", \"latency_unittests\": \"08b920c163c3bb4e0391eca7edb94af60b8de9aa\", \"libjingle_xmpp_unittests\": \"cfa3c0612b035eb787dd1bbc8b2e17d513c8c7ea\", \"liburlpattern_unittests\": \"dede7794a781c793adfa00c79d59fb5ff7cdb05a\", \"media_blink_unittests\": \"5cdd766343090c50d078f2a2c2cd7474efebeda8\", \"media_unittests\": \"934cfe2b255c91e81840ead925f1232c698e73e8\", \"message_center_unittests\": \"355e12b7b026aa27b58c031e1c3f9b2723280ad2\", \"midi_unittests\": \"1487ab2fc878648369a4db2fbfb8d50e4720214b\", \"mojo_core_unittests\": \"8ff8899e2a05362d1bbf2236b1dc214a05f9e4d2\", \"mojo_unittests\": \"e4ec5952d5fbc5c0765deb345de03c49dd21dfa1\", \"nacl_loader_unittests\": \"3b28724937c427eda03a0c2042820a2de4ad28a6\", \"native_theme_unittests\": \"d38e23ea46440b87e3aee4e4344f4c735053bcd8\", \"net_unittests\": \"1bd52a86ee602a904c2aea2e846a9261b567bf9a\", \"notification_helper_unittests\": \"c03d0b31a434d73786552c8aac285791490cf9f7\", \"pdf_unittests\": \"e733d1ffc5a61423178f94cafe2758795f070e84\", \"perfetto_unittests\": \"ef71ea265dec3e2eeb45db978e8c2c67063d5209\", \"ppapi_unittests\": \"7c0a0f97bb39d51b814db0ab488ea28fb0b53939\", \"printing_unittests\": \"3e7feb4d428c106a9b3607e5e3e2ba55f9c24df2\", \"remoting_unittests\": \"76321dde4313a0be70ec46f02b945cb1de7ea096\", \"sbox_integration_tests\": \"e035a0c820f89feca1266c7366399a28d34e3d16\", \"sbox_unittests\": \"bd37207dc589b33a0a14b5dd25b8a47cdd5daf6a\", \"sbox_validation_tests\": \"b75a2ad01ffaee754b4ff5d8850f5063ff477e4b\", \"service_manager_unittests\": \"517fc8a14357c05719bd04b0ef08a6c14f5b713a\", \"services_unittests\": \"de577aabe3e15fe022167cd52d5703089cd22659\", \"setup_unittests\": \"d5d2933cb255b5cd5bd02771e8d7c1dc9a4e45f2\", \"shell_dialogs_unittests\": \"ac0232f49db9de9445cf1d4102c7377f0e300d27\", \"skia_unittests\": \"2dbfbce86d8308761142230f21e24feb964d85a5\", \"snapshot_unittests\": \"33edad6ca019d3115d836a15c2b1536db6f65ded\", \"sql_unittests\": \"137ee8ec2cc56e57eb70b73916bf185972d4e981\", \"storage_unittests\": \"66445b57aef8d9188ab80548e76697d0f4953d06\", \"sync_integration_tests\": \"a61f7a2e87e3e0693d95c03d6eb94e0ecaadb263\", \"traffic_annotation_auditor_unittests\": \"fc1f0a4772f873990870f85acfba7be971baedf3\", \"ui_base_unittests\": \"001604597d84c0b8122bc81c248845f94752dd63\", \"ui_touch_selection_unittests\": \"9c6d14be53ab599d72012119e2a97e8dcfe84b91\", \"unit_tests\": \"bda5a5b811bce1bd3cfe90d3691fbeb72d907819\", \"updater_tests\": \"ab77bdbb7bdd9471f7c70081a926b2abedfd84e0\", \"url_unittests\": \"466e8e78445c2bf29ca91b5445f673829b8eb2f7\", \"views_unittests\": \"dfff1d920ade3aafa8090f7a532da1291cde166d\", \"viz_unittests\": \"b4cd72057f70a7a7a50da4b2588e0d725bd0f61f\", \"vr_common_unittests\": \"793583a96541c7787e0b266233775deabe4cf285\", \"vr_pixeltests\": \"0a8da303928fed7b7b9d4727adda051a74088dba\", \"weblayer_browsertests\": \"c2a8eb1b9d5fb316e1b1b2246572630b4493d841\", \"weblayer_unittests\": \"49f4495a25263bcbcfa6a098f50940066b23957b\", \"wm_unittests\": \"bf5f1b0d977055756c484a9362ff21480865b746\", \"wtf_unittests\": \"f047e222fa91247ac1de802be64ec1363def8294\", \"zlib_unittests\": \"8e3ff638e3098a5c838ad61e35aca5614ff23f18\", \"zucchini_unittests\": \"d44ccc6f256440d523608fe7494f107634f535a8\"}}, \"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-9-6o02\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"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\": [\"5614-d862eaa\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**gcp_unittests** failed because of:\\n\\n- All/AssociatedUserValidatorUserAccessBlockingTest.BlockUserAccessAsNeeded/21519\\n\\n- All/AssociatedUserValidatorUserAccessBlockingTest.BlockUserAccessAsNeeded/21518\\n\\n- All/AssociatedUserValidatorUserAccessBlockingTest.BlockUserAccessAsNeeded/21515\\n\\n- All/AssociatedUserValidatorUserAccessBlockingTest.BlockUserAccessAsNeeded/21514\\n\\n- ...27471 more failure(s) (27475 total)...\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1604098040832453",
"status": "COMPLETED",
"status_changed_ts": "1604098042249184",
"tags": [
"build_address:luci.chromium.ci/win-asan/15374",
"builder:win-asan",
"buildset:commit/git/16ccdc07d59cc5e007c594e593ac0436e1c3d399",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/16ccdc07d59cc5e007c594e593ac0436e1c3d399",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030405319058983088",
"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/8864975256109706464/+/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:4f947f9609aa3510",
"user_agent:luci-scheduler"
],
"updated_ts": "1604104946555616",
"url": "https://ci.chromium.org/b/8864975256109706464",
"utcnow_ts": "1604104947014809"
}
}
[00:42:27.031] Invocation finished in 1h59m4.348255644s with status FAILED