[12:24:24.585] New invocation is queued and will start shortly
[12:24:25.701] Starting the invocation (attempt 1)
[12:24:25.743] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:24:25.743] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:24:25.743] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019391431292602240",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"001504aaff0ab0346d5bb1837fa12d0d33110f1d\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9019391431292602240",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/001504aaff0ab0346d5bb1837fa12d0d33110f1d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/001504aaff0ab0346d5bb1837fa12d0d33110f1d",
"gitiles_ref:refs/heads/master"
]
}
[12:24:26.359] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614601465831438",
"id": "8853961369323065600",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"001504aaff0ab0346d5bb1837fa12d0d33110f1d\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614601466254393",
"tags": [
"build_address:luci.chromium.ci/win-asan/17574",
"builder:win-asan",
"buildset:commit/git/001504aaff0ab0346d5bb1837fa12d0d33110f1d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/001504aaff0ab0346d5bb1837fa12d0d33110f1d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019391431292602240",
"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/8853961369323065600/+/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": "1614601466254502",
"url": "https://ci.chromium.org/b/8853961369323065600",
"utcnow_ts": "1614601466352233"
}
}
[12:24:26.359] Task URL: https://ci.chromium.org/b/8853961369323065600
[12:24:26.359] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:2:0) after 1m0s
[12:25:26.409] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:2:0)
[12:25:26.409] Timer tick, asking Buildbucket for the build status
[12:25:26.488] Build 8853961369323065600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:25:26.488] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:3:0) after 1m0s
[12:26:26.506] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:3:0)
[12:26:26.506] Timer tick, asking Buildbucket for the build status
[12:26:26.572] Build 8853961369323065600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:26:26.572] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:4:0) after 1m0s
[12:27:20.782] Received PubSub notification, asking Buildbucket for the build status
[12:27:20.866] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:26.601] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:4:0)
[12:27:26.601] Timer tick, asking Buildbucket for the build status
[12:27:26.715] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:26.715] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:6:0) after 1m0s
[12:28:26.780] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:6:0)
[12:28:26.780] Timer tick, asking Buildbucket for the build status
[12:28:28.939] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:28.939] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:7:0) after 1m0s
[12:29:29.027] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:7:0)
[12:29:29.027] Timer tick, asking Buildbucket for the build status
[12:29:29.133] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:29.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:8:0) after 1m0s
[12:30:29.183] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:8:0)
[12:30:29.183] Timer tick, asking Buildbucket for the build status
[12:30:29.401] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:29.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:9:0) after 1m0s
[12:31:29.469] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:9:0)
[12:31:29.469] Timer tick, asking Buildbucket for the build status
[12:31:30.204] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:30.204] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:10:0) after 1m0s
[12:32:30.306] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:10:0)
[12:32:30.306] Timer tick, asking Buildbucket for the build status
[12:32:30.547] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:30.547] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:11:0) after 1m0s
[12:33:30.588] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:11:0)
[12:33:30.588] Timer tick, asking Buildbucket for the build status
[12:33:30.844] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:30.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:12:0) after 1m0s
[12:34:30.883] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:12:0)
[12:34:30.883] Timer tick, asking Buildbucket for the build status
[12:34:30.971] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:30.971] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:13:0) after 1m0s
[12:35:30.985] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:13:0)
[12:35:30.986] Timer tick, asking Buildbucket for the build status
[12:35:31.502] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:31.502] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:14:0) after 1m0s
[12:36:31.542] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:14:0)
[12:36:31.542] Timer tick, asking Buildbucket for the build status
[12:36:31.738] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:31.738] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:15:0) after 1m0s
[12:37:31.837] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:15:0)
[12:37:31.837] Timer tick, asking Buildbucket for the build status
[12:37:31.909] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:31.909] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:16:0) after 1m0s
[12:38:32.000] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:16:0)
[12:38:32.000] Timer tick, asking Buildbucket for the build status
[12:38:32.151] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:32.151] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:17:0) after 1m0s
[12:39:32.185] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:17:0)
[12:39:32.185] Timer tick, asking Buildbucket for the build status
[12:39:32.326] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:32.326] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:18:0) after 1m0s
[12:40:32.334] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:18:0)
[12:40:32.334] Timer tick, asking Buildbucket for the build status
[12:40:32.417] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:32.417] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:19:0) after 1m0s
[12:41:32.575] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:19:0)
[12:41:32.575] Timer tick, asking Buildbucket for the build status
[12:41:33.005] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:33.005] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:20:0) after 1m0s
[12:42:33.001] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:20:0)
[12:42:33.001] Timer tick, asking Buildbucket for the build status
[12:42:33.557] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:33.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:21:0) after 1m0s
[12:43:33.591] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:21:0)
[12:43:33.591] Timer tick, asking Buildbucket for the build status
[12:43:33.666] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:33.666] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:22:0) after 1m0s
[12:44:33.737] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:22:0)
[12:44:33.737] Timer tick, asking Buildbucket for the build status
[12:44:33.827] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:33.827] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:23:0) after 1m0s
[12:45:33.846] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:23:0)
[12:45:33.846] Timer tick, asking Buildbucket for the build status
[12:45:33.957] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:33.957] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:24:0) after 1m0s
[12:46:34.016] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:24:0)
[12:46:34.016] Timer tick, asking Buildbucket for the build status
[12:46:34.093] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:34.093] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:25:0) after 1m0s
[12:47:34.198] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:25:0)
[12:47:34.198] Timer tick, asking Buildbucket for the build status
[12:47:34.394] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:34.394] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:26:0) after 1m0s
[12:48:34.384] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:26:0)
[12:48:34.384] Timer tick, asking Buildbucket for the build status
[12:48:34.529] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:34.529] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:27:0) after 1m0s
[12:49:34.586] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:27:0)
[12:49:34.586] Timer tick, asking Buildbucket for the build status
[12:49:34.748] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:34.748] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:28:0) after 1m0s
[12:50:34.783] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:28:0)
[12:50:34.783] Timer tick, asking Buildbucket for the build status
[12:50:34.857] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:34.857] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:29:0) after 1m0s
[12:51:34.877] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:29:0)
[12:51:34.877] Timer tick, asking Buildbucket for the build status
[12:51:34.945] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:34.945] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:30:0) after 1m0s
[12:52:35.002] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:30:0)
[12:52:35.002] Timer tick, asking Buildbucket for the build status
[12:52:35.067] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:35.067] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:31:0) after 1m0s
[12:53:35.083] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:31:0)
[12:53:35.083] Timer tick, asking Buildbucket for the build status
[12:53:35.302] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:35.302] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:32:0) after 1m0s
[12:54:35.427] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:32:0)
[12:54:35.427] Timer tick, asking Buildbucket for the build status
[12:54:35.503] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:35.503] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:33:0) after 1m0s
[12:55:35.549] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:33:0)
[12:55:35.549] Timer tick, asking Buildbucket for the build status
[12:55:35.716] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:35.716] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:34:0) after 1m0s
[12:56:36.290] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:34:0)
[12:56:36.290] Timer tick, asking Buildbucket for the build status
[12:56:36.422] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:36.422] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:35:0) after 1m0s
[12:57:36.486] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:35:0)
[12:57:36.486] Timer tick, asking Buildbucket for the build status
[12:57:36.587] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:36.587] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:36:0) after 1m0s
[12:58:36.584] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:36:0)
[12:58:36.584] Timer tick, asking Buildbucket for the build status
[12:58:36.654] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:36.654] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:37:0) after 1m0s
[12:59:36.674] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:37:0)
[12:59:36.674] Timer tick, asking Buildbucket for the build status
[12:59:36.778] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:36.778] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:38:0) after 1m0s
[13:00:36.805] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:38:0)
[13:00:36.805] Timer tick, asking Buildbucket for the build status
[13:00:37.129] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:37.129] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:39:0) after 1m0s
[13:01:37.164] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:39:0)
[13:01:37.164] Timer tick, asking Buildbucket for the build status
[13:01:37.229] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:37.229] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:40:0) after 1m0s
[13:02:37.234] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:40:0)
[13:02:37.234] Timer tick, asking Buildbucket for the build status
[13:02:37.307] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:37.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:41:0) after 1m0s
[13:03:37.326] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:41:0)
[13:03:37.326] Timer tick, asking Buildbucket for the build status
[13:03:37.386] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:37.386] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:42:0) after 1m0s
[13:04:37.409] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:42:0)
[13:04:37.409] Timer tick, asking Buildbucket for the build status
[13:04:37.485] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:37.485] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:43:0) after 1m0s
[13:05:37.516] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:43:0)
[13:05:37.516] Timer tick, asking Buildbucket for the build status
[13:05:37.610] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:37.610] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:44:0) after 1m0s
[13:06:37.638] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:44:0)
[13:06:37.638] Timer tick, asking Buildbucket for the build status
[13:06:37.965] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:37.965] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:45:0) after 1m0s
[13:07:37.980] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:45:0)
[13:07:37.980] Timer tick, asking Buildbucket for the build status
[13:07:38.088] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:38.089] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:46:0) after 1m0s
[13:08:38.140] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:46:0)
[13:08:38.140] Timer tick, asking Buildbucket for the build status
[13:08:38.293] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:38.293] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:47:0) after 1m0s
[13:09:38.330] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:47:0)
[13:09:38.330] Timer tick, asking Buildbucket for the build status
[13:09:38.646] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:38.646] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:48:0) after 1m0s
[13:10:38.727] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:48:0)
[13:10:38.727] Timer tick, asking Buildbucket for the build status
[13:10:38.919] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:38.919] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:49:0) after 1m0s
[13:11:38.990] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:49:0)
[13:11:38.990] Timer tick, asking Buildbucket for the build status
[13:11:39.053] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:39.053] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:50:0) after 1m0s
[13:12:39.067] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:50:0)
[13:12:39.067] Timer tick, asking Buildbucket for the build status
[13:12:39.146] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:39.146] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:51:0) after 1m0s
[13:13:39.212] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:51:0)
[13:13:39.212] Timer tick, asking Buildbucket for the build status
[13:13:39.564] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:39.564] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:52:0) after 1m0s
[13:14:39.638] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:52:0)
[13:14:39.638] Timer tick, asking Buildbucket for the build status
[13:14:39.699] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:39.699] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:53:0) after 1m0s
[13:15:39.729] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:53:0)
[13:15:39.729] Timer tick, asking Buildbucket for the build status
[13:15:39.838] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:39.838] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:54:0) after 1m0s
[13:16:39.900] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:54:0)
[13:16:39.900] Timer tick, asking Buildbucket for the build status
[13:16:39.978] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:39.978] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:55:0) after 1m0s
[13:17:39.998] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:55:0)
[13:17:39.998] Timer tick, asking Buildbucket for the build status
[13:17:40.126] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:40.126] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:56:0) after 1m0s
[13:18:40.188] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:56:0)
[13:18:40.188] Timer tick, asking Buildbucket for the build status
[13:18:40.426] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:40.426] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:57:0) after 1m0s
[13:19:40.498] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:57:0)
[13:19:40.498] Timer tick, asking Buildbucket for the build status
[13:19:40.832] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:40.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:58:0) after 1m0s
[13:20:40.884] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:58:0)
[13:20:40.884] Timer tick, asking Buildbucket for the build status
[13:20:40.963] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:40.963] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:59:0) after 1m0s
[13:21:41.022] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:59:0)
[13:21:41.022] Timer tick, asking Buildbucket for the build status
[13:21:41.093] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:41.093] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:60:0) after 1m0s
[13:22:41.124] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:60:0)
[13:22:41.125] Timer tick, asking Buildbucket for the build status
[13:22:41.189] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:41.189] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:61:0) after 1m0s
[13:23:41.240] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:61:0)
[13:23:41.240] Timer tick, asking Buildbucket for the build status
[13:23:41.312] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:41.312] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:62:0) after 1m0s
[13:24:41.294] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:62:0)
[13:24:41.294] Timer tick, asking Buildbucket for the build status
[13:24:41.362] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:41.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:63:0) after 1m0s
[13:25:41.907] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:63:0)
[13:25:41.907] Timer tick, asking Buildbucket for the build status
[13:25:42.002] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:42.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:64:0) after 1m0s
[13:26:42.113] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:64:0)
[13:26:42.113] Timer tick, asking Buildbucket for the build status
[13:26:42.336] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:42.336] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:65:0) after 1m0s
[13:27:42.362] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:65:0)
[13:27:42.362] Timer tick, asking Buildbucket for the build status
[13:27:42.475] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:42.475] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:66:0) after 1m0s
[13:28:42.492] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:66:0)
[13:28:42.492] Timer tick, asking Buildbucket for the build status
[13:28:42.578] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:42.578] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:67:0) after 1m0s
[13:29:42.593] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:67:0)
[13:29:42.593] Timer tick, asking Buildbucket for the build status
[13:29:42.660] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:42.660] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:68:0) after 1m0s
[13:30:42.714] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:68:0)
[13:30:42.714] Timer tick, asking Buildbucket for the build status
[13:30:42.800] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:42.800] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:69:0) after 1m0s
[13:31:42.862] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:69:0)
[13:31:42.862] Timer tick, asking Buildbucket for the build status
[13:31:42.942] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:42.942] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:70:0) after 1m0s
[13:32:43.091] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:70:0)
[13:32:43.091] Timer tick, asking Buildbucket for the build status
[13:32:43.189] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:43.190] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:71:0) after 1m0s
[13:33:43.257] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:71:0)
[13:33:43.257] Timer tick, asking Buildbucket for the build status
[13:33:43.454] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:43.454] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:72:0) after 1m0s
[13:34:43.556] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:72:0)
[13:34:43.556] Timer tick, asking Buildbucket for the build status
[13:34:43.639] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:43.639] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:73:0) after 1m0s
[13:35:43.691] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:73:0)
[13:35:43.691] Timer tick, asking Buildbucket for the build status
[13:35:43.833] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:43.833] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:74:0) after 1m0s
[13:36:43.841] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:74:0)
[13:36:43.841] Timer tick, asking Buildbucket for the build status
[13:36:43.945] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:43.945] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:75:0) after 1m0s
[13:37:44.035] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:75:0)
[13:37:44.035] Timer tick, asking Buildbucket for the build status
[13:37:44.136] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:44.136] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:76:0) after 1m0s
[13:38:44.203] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:76:0)
[13:38:44.203] Timer tick, asking Buildbucket for the build status
[13:38:44.442] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:44.442] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:77:0) after 1m0s
[13:39:44.470] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:77:0)
[13:39:44.470] Timer tick, asking Buildbucket for the build status
[13:39:44.545] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:44.545] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:78:0) after 1m0s
[13:40:44.563] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:78:0)
[13:40:44.563] Timer tick, asking Buildbucket for the build status
[13:40:44.715] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:44.715] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:79:0) after 1m0s
[13:41:44.764] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:79:0)
[13:41:44.764] Timer tick, asking Buildbucket for the build status
[13:41:44.866] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:44.866] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:80:0) after 1m0s
[13:42:44.939] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:80:0)
[13:42:44.939] Timer tick, asking Buildbucket for the build status
[13:42:45.016] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:45.016] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:81:0) after 1m0s
[13:43:45.153] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:81:0)
[13:43:45.153] Timer tick, asking Buildbucket for the build status
[13:43:45.240] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:45.240] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:82:0) after 1m0s
[13:44:45.292] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:82:0)
[13:44:45.292] Timer tick, asking Buildbucket for the build status
[13:44:45.368] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:45.368] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:83:0) after 1m0s
[13:45:45.378] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:83:0)
[13:45:45.378] Timer tick, asking Buildbucket for the build status
[13:45:45.514] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:45.514] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:84:0) after 1m0s
[13:46:45.533] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:84:0)
[13:46:45.533] Timer tick, asking Buildbucket for the build status
[13:46:45.616] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:45.616] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:85:0) after 1m0s
[13:47:45.769] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:85:0)
[13:47:45.769] Timer tick, asking Buildbucket for the build status
[13:47:45.897] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:45.897] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:86:0) after 1m0s
[13:48:45.983] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:86:0)
[13:48:45.983] Timer tick, asking Buildbucket for the build status
[13:48:46.060] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:46.060] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:87:0) after 1m0s
[13:49:46.130] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:87:0)
[13:49:46.130] Timer tick, asking Buildbucket for the build status
[13:49:46.462] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:46.462] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:88:0) after 1m0s
[13:50:46.481] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:88:0)
[13:50:46.481] Timer tick, asking Buildbucket for the build status
[13:50:46.952] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:46.952] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:89:0) after 1m0s
[13:51:46.964] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:89:0)
[13:51:46.964] Timer tick, asking Buildbucket for the build status
[13:51:47.079] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:47.079] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:90:0) after 1m0s
[13:52:47.101] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:90:0)
[13:52:47.101] Timer tick, asking Buildbucket for the build status
[13:52:47.460] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:47.460] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:91:0) after 1m0s
[13:53:47.539] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:91:0)
[13:53:47.539] Timer tick, asking Buildbucket for the build status
[13:53:47.611] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:47.611] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:92:0) after 1m0s
[13:54:47.638] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:92:0)
[13:54:47.638] Timer tick, asking Buildbucket for the build status
[13:54:47.766] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:47.766] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:93:0) after 1m0s
[13:55:47.809] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:93:0)
[13:55:47.809] Timer tick, asking Buildbucket for the build status
[13:55:47.931] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:47.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:94:0) after 1m0s
[13:56:47.955] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:94:0)
[13:56:47.955] Timer tick, asking Buildbucket for the build status
[13:56:48.052] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:48.052] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:95:0) after 1m0s
[13:57:48.276] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:95:0)
[13:57:48.276] Timer tick, asking Buildbucket for the build status
[13:57:48.500] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:48.500] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:96:0) after 1m0s
[13:58:48.532] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:96:0)
[13:58:48.532] Timer tick, asking Buildbucket for the build status
[13:58:48.620] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:48.620] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:97:0) after 1m0s
[13:59:48.805] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:97:0)
[13:59:48.805] Timer tick, asking Buildbucket for the build status
[13:59:48.937] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:48.937] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:98:0) after 1m0s
[14:00:49.065] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:98:0)
[14:00:49.065] Timer tick, asking Buildbucket for the build status
[14:00:49.177] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:49.177] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:99:0) after 1m0s
[14:01:49.189] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:99:0)
[14:01:49.189] Timer tick, asking Buildbucket for the build status
[14:01:49.288] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:49.288] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:100:0) after 1m0s
[14:02:49.307] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:100:0)
[14:02:49.307] Timer tick, asking Buildbucket for the build status
[14:02:49.403] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:49.403] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:101:0) after 1m0s
[14:03:49.452] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:101:0)
[14:03:49.452] Timer tick, asking Buildbucket for the build status
[14:03:49.536] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:49.536] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:102:0) after 1m0s
[14:04:49.578] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:102:0)
[14:04:49.578] Timer tick, asking Buildbucket for the build status
[14:04:49.684] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:49.684] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:103:0) after 1m0s
[14:05:49.767] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:103:0)
[14:05:49.767] Timer tick, asking Buildbucket for the build status
[14:05:49.912] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:49.912] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:104:0) after 1m0s
[14:06:49.939] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:104:0)
[14:06:49.940] Timer tick, asking Buildbucket for the build status
[14:06:50.025] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:50.025] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:105:0) after 1m0s
[14:07:50.089] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:105:0)
[14:07:50.089] Timer tick, asking Buildbucket for the build status
[14:07:50.236] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:50.236] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:106:0) after 1m0s
[14:08:50.254] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:106:0)
[14:08:50.254] Timer tick, asking Buildbucket for the build status
[14:08:50.361] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:50.361] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:107:0) after 1m0s
[14:09:50.380] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:107:0)
[14:09:50.380] Timer tick, asking Buildbucket for the build status
[14:09:50.540] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:50.540] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:108:0) after 1m0s
[14:10:50.545] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:108:0)
[14:10:50.545] Timer tick, asking Buildbucket for the build status
[14:10:50.860] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:50.860] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:109:0) after 1m0s
[14:11:50.870] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:109:0)
[14:11:50.870] Timer tick, asking Buildbucket for the build status
[14:11:50.968] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:50.968] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:110:0) after 1m0s
[14:12:51.131] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:110:0)
[14:12:51.131] Timer tick, asking Buildbucket for the build status
[14:12:52.800] Build 8853961369323065600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:52.800] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019391431292602240:111:0) after 1m0s
[14:13:33.432] Received PubSub notification, asking Buildbucket for the build status
[14:13:33.571] Build 8853961369323065600: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[14:13:33.571] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614608013029331",
"created_by": "project:chromium",
"created_ts": "1614601465831438",
"failure_reason": "BUILD_FAILURE",
"id": "8853961369323065600",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"001504aaff0ab0346d5bb1837fa12d0d33110f1d\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- ThreatDOMDetailsTest.Everything\\n\\n- TranslateAgentBrowserTest.TranslateLibNeverReady\\n\\n- SubresourceRedirectPublicImageHintsDeciderAgentTest.TestGetSubresourceURL\\n\\n- PasswordGenerationAgentTest.GenerationFallback_NoFocusedElement\\n\\n- ...5 more failure(s) (9 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\": \"001504aaff0ab0346d5bb1837fa12d0d33110f1d\", \"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-2-2mmz\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17574, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-3\", \"got_angle_revision\": \"a6d4623970eda91638bd3c3638c9104bffcaa316\", \"got_dawn_revision\": \"19f111f69c550f5cddec76557a0b7808adae9c66\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"001504aaff0ab0346d5bb1837fa12d0d33110f1d\", \"got_revision_cp\": \"refs/heads/master@{#858529}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"106e01583a3ac2fb950777390dde2dcc31a8f161\", \"got_v8_revision\": \"45e415150419491d6d12428ba6ee98876a405ba4\", \"got_v8_revision_cp\": \"refs/heads/9.1.1@{#1}\", \"got_webrtc_revision\": \"376cf384ac689eca0bf8d4d1d3a3cf80888d3fbb\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33348}\", \"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\": \"001504aaff0ab0346d5bb1837fa12d0d33110f1d\", \"swarm_hashes_refs/heads/master(at){#858529}_without_patch\": {\"absl_hardening_tests\": \"f5e1ce4ad7e40415ddea9e8ef815f5b57a0d2c18\", \"accessibility_unittests\": \"573ed0fce06b7b78789a3d98c5b076df6585947e\", \"angle_unittests\": \"d16139ca87faefcc8efaa81246ec860b298df0cd\", \"app_shell_unittests\": \"dfeb91c2128eca775a59a5ddf9f204a6c7bd7a74\", \"aura_unittests\": \"b9358ace197460fd426d561257ceba9b50eefb8a\", \"base_unittests\": \"36d6310ffc0201a11a750f788404f57003ccc750\", \"base_util_unittests\": \"390d6530e4fa409c433121b77371dd212ad1af41\", \"blink_common_unittests\": \"c0d97f432437ed4ca7bf7bd3ecb268e87db6217d\", \"blink_fuzzer_unittests\": \"6cfadb59a9814ac182156456e639639e9e2190b8\", \"blink_heap_unittests\": \"7f51ed983d8733efa1920d6fa3009b2a49582bae\", \"blink_platform_unittests\": \"877725730d80c9db945467228024488c7d3f0b0a\", \"blink_unittests\": \"0f82849d0cb046e0a95bd94ba844da4e87a0e724\", \"boringssl_crypto_tests\": \"528ebea48188e1814a6ee22def74c8231dc74003\", \"boringssl_ssl_tests\": \"d596a7b849e989259aeec311e9cc7773ee3c68b4\", \"browser_switcher_bho_unittests\": \"3dbf17372a522a319ccc3069a550a9967bdaf8cb\", \"browser_tests\": \"3f23584eb3c563126ae4144d962251b965ac1813\", \"capture_unittests\": \"742008ce815097fd373565b9a1026ee6096a2bf8\", \"cast_unittests\": \"3861608d685d1e6d56d1bc69821d6a2b5270d602\", \"cc_unittests\": \"6a20efd839ee176c12ffa89d537364ef62029fe5\", \"chrome_app_unittests\": \"dbf8e7602f1443d651aa0cbce2e90f346673b3ea\", \"chrome_cleaner_unittests\": \"c868bc52b3822ff6e41067e47b7824732e9e8fe3\", \"chrome_elf_unittests\": \"660f8b93c826157e90cc7c915a76ded5dc8cf5de\", \"chromedriver_unittests\": \"0a1d7ff292c679e63969f905c68b68d00de5acf1\", \"color_unittests\": \"9ebf01369163a95552727ecacbe4e0675ab300fa\", \"components_browsertests\": \"04f61bb681cb6bfcac9af2024847739e5aabc2ad\", \"components_unittests\": \"8e6dd8162bf6264221d60319d940f860a627fa5c\", \"compositor_unittests\": \"a70194a9dee3fcfbf3c1091667501ff5b7bc6994\", \"content_browsertests\": \"d2ff2ccb6eeb21ff477f81874a6eb2a0adecf667\", \"content_unittests\": \"39cf8414ac319bb1a8917f188a35ea4e992e92c2\", \"courgette_unittests\": \"59c59bab3c51ca8e94da45218a5dde1d2ef5ab67\", \"crashpad_tests\": \"1b9426b7d4fc6473e0a161573de9a49794f93a07\", \"cronet_tests\": \"38b0119d04d84c1079122154c5fbb2e8469d54bd\", \"cronet_unittests\": \"ed465646797309d1315dea00c63ccf039758ef35\", \"crypto_unittests\": \"cddc7164b413377d2e880e5ca5487d9c924cb581\", \"delayloads_unittests\": \"79208f7e923220fcfd66406c42bb3e0a73876fb7\", \"device_unittests\": \"78e8d255a60ad4f64db2ab4aad5dccc7b72f8670\", \"display_unittests\": \"318d0b8bf4cbdb73edc680eaddc11e0f309590f8\", \"elevation_service_unittests\": \"ae303d727ffa3a38f6826bd72ea3fb083bc9c015\", \"events_unittests\": \"9f41ca5896fe5a42672cc2771b3008ca3b24e202\", \"extensions_browsertests\": \"7a3c8f58f533640c95ee87f41e5c2ae64236aeb9\", \"extensions_unittests\": \"7600afee50885ab7210c11ce1ffaaa62b8d6af34\", \"filesystem_service_unittests\": \"577eed73d70c72492f4e48b21083357914f543fd\", \"gcm_unit_tests\": \"a142c2494aea1ef89a545ef941b1f683aee29264\", \"gcp_unittests\": \"6005325debb19506821e47b77110e39b6f0cc69c\", \"gfx_unittests\": \"b678e79f776903c5028bed88d3980b95105dfa03\", \"gin_unittests\": \"15e9e19310b2e9e4d3570b5c40b1de28de387dbf\", \"google_apis_unittests\": \"15dcce0c4a7f6005b239fcdfc7d05763acb6ca84\", \"gpu_unittests\": \"033cb4752b7add6f181b5f3d3ad238ba5683ac43\", \"gwp_asan_unittests\": \"6deb2b30fcdd3958858f8ec97653377a5dca3a2f\", \"headless_browsertests\": \"d25d8a3e6b78e0c053774cc21d8c18e95de8d47b\", \"headless_unittests\": \"baeae61419a0517209fab4a229829fcd0a6a35a2\", \"install_static_unittests\": \"7c7a5df3bdea6c01a550383414059840109b2f9c\", \"installer_util_unittests\": \"4d97d631bd177175175de72234acfe9bc89f60e8\", \"interactive_ui_tests\": \"8075a90fed667c6cdfdb28c74243443442277707\", \"ipc_tests\": \"d64ccb2e331b003fec3280392150cacf5937dd86\", \"jingle_unittests\": \"b99a4534f769f43a71de38fa89a617856e4daee6\", \"latency_unittests\": \"fead5eb05051a9725665156bc8a1c6ac4f7c102b\", \"libjingle_xmpp_unittests\": \"fae9c8c71df405a6f926c4fcd79bc03fed54a728\", \"liburlpattern_unittests\": \"978bab83b37b4e12fcc9513bcc904ee465d152ac\", \"media_blink_unittests\": \"d91d2a2b23f0a196172c13dc305cede19b480073\", \"media_unittests\": \"49bc5b2c263f80e32592f7c0fa9c5d00735735b9\", \"message_center_unittests\": \"db246664f2aef6a7ae1ed03afcf8e73c701e75e4\", \"midi_unittests\": \"70f1246977b2698c86199196a515162b8fdb2771\", \"mojo_core_unittests\": \"ca9350603b6c3e09a40423b98aecac4bed421aa6\", \"mojo_unittests\": \"0899a3a767b2fe6d09f8e68552a7f3da094fac30\", \"nacl_loader_unittests\": \"a005b6dfbf777ab901337c34aaf24a6fbcdd7111\", \"native_theme_unittests\": \"08040fcebd20b518f4a2ba03c59734f20d84fbf0\", \"net_unittests\": \"b447b1b0d498b5461bbbc80bbb41531f516b1009\", \"notification_helper_unittests\": \"36fef720cecdcc61f5bc3bf21c7cc8d1fb49e42a\", \"pdf_unittests\": \"015342cf4b0cdbd362e29028366298d1ee6d4547\", \"perfetto_unittests\": \"05d2c7111aa4ba8c37b26a3013e1090d026aeecf\", \"ppapi_unittests\": \"6b3c7e49b27cdca87dc66561a43b6a30bf53a13e\", \"printing_unittests\": \"f926c1a1b195c47b273dda2605740d57424bd2c1\", \"remoting_unittests\": \"6f9bda4170d4099ac415fa7f36ee48b323943fa7\", \"sbox_integration_tests\": \"4f4b59681d9e334cf4f004bb454f2eae85b2292d\", \"sbox_unittests\": \"4f542d709d703943db53b68924d3f0bc0ac786c0\", \"sbox_validation_tests\": \"8d4b53e9f495aa47eae28d07f5ff7c3586da1251\", \"service_manager_unittests\": \"15e84a665cf49088c7773a3f1d86a80dc8077a27\", \"services_unittests\": \"cd4978087b04a510d464cc54149e89e7f95223fa\", \"setup_unittests\": \"bb4d378df2131c7db64eafd65c10740d9814c54c\", \"shell_dialogs_unittests\": \"b8969aa3e83ec4607f5c9351bab5a1db3f8a447e\", \"skia_unittests\": \"469ca0511cea67c46887d179461b672738eaa6eb\", \"snapshot_unittests\": \"9e25241f15aa22748fa3766558daf9ee545d87a3\", \"sql_unittests\": \"42bfde58e860dffbc3c5d69c33dddbdf4ad6b498\", \"storage_unittests\": \"8579420793bd7a12eae716c289e46ce4d2c6de72\", \"sync_integration_tests\": \"5fc24bdd5e47836f26c87f234a7bcea84894674b\", \"traffic_annotation_auditor_unittests\": \"3c0dc76f612c54b97d7569b54e01b463f6fc019a\", \"ui_base_unittests\": \"a639b39f17acf77e48457b0d3f0e9ad3d880df37\", \"ui_touch_selection_unittests\": \"83d69fce4e4d2429c8b4463e4d8abeace558ba7f\", \"unit_tests\": \"9f4ac51420e6ea16e2933065b5492081067dbf80\", \"updater_tests\": \"fb91783e4e6f407cbc02f66e2d6fd1295d185878\", \"url_unittests\": \"4d358564449252b42f89503d20dd9300bf13483a\", \"views_unittests\": \"4ba764e5b1a5ab6379eafe8853020f28263211d9\", \"viz_unittests\": \"f07dbe365f315dfac29b5ceece122bea938a0e44\", \"vr_common_unittests\": \"5d89e88d6d93288c06f1b64256f434da5750bf2a\", \"vr_pixeltests\": \"cc5ec23d65fede7e6dd0a519ade98d9d82ee210f\", \"weblayer_browsertests\": \"93a737e22714e516af2fc3295c9566becfb8031f\", \"weblayer_unittests\": \"64c5157cbd3d79532ed8d8948018d06e36d70d96\", \"wm_unittests\": \"96d0ca84f8a93f58a6cc34b359ec2f06b5de7028\", \"wtf_unittests\": \"13d711b449040e0d6951a3030e5ec36c262fbc8a\", \"zlib_unittests\": \"2d4ff3fa2ab1390d2327e6f5a9a147984bfad37e\", \"zucchini_unittests\": \"312d32fda38a462031f96f88a44c6a8f9e23ae22\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_02613bacee8cd1c41158238da4ce7615021ecb28a4b52e6029f89cb601af0790_v2\", \"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-2-2mmz\"], \"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\": [\"5712-339372b\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- ThreatDOMDetailsTest.Everything\\n\\n- TranslateAgentBrowserTest.TranslateLibNeverReady\\n\\n- SubresourceRedirectPublicImageHintsDeciderAgentTest.TestGetSubresourceURL\\n\\n- PasswordGenerationAgentTest.GenerationFallback_NoFocusedElement\\n\\n- ...5 more failure(s) (9 total)...\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1614601639723619",
"status": "COMPLETED",
"status_changed_ts": "1614601640628308",
"tags": [
"build_address:luci.chromium.ci/win-asan/17574",
"builder:win-asan",
"buildset:commit/git/001504aaff0ab0346d5bb1837fa12d0d33110f1d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/001504aaff0ab0346d5bb1837fa12d0d33110f1d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019391431292602240",
"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/8853961369323065600/+/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:520690ba71b0f910",
"user_agent:luci-scheduler"
],
"updated_ts": "1614608013029739",
"url": "https://ci.chromium.org/b/8853961369323065600",
"utcnow_ts": "1614608013556350"
}
}
[14:13:33.571] Invocation finished in 1h49m9.002404274s with status FAILED