[08:32:46.836] New invocation is queued and will start shortly
[08:32:47.968] Starting the invocation (attempt 1)
[08:32:48.003] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:32:48.003] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:32:48.003] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021852122233521392",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"5a3ad4823597a66129edb847f70a97de6c9d6d1f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9021852122233521392",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/5a3ad4823597a66129edb847f70a97de6c9d6d1f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5a3ad4823597a66129edb847f70a97de6c9d6d1f",
"gitiles_ref:refs/heads/master"
]
}
[08:32:49.309] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612254768139790",
"id": "8856422060202165824",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5a3ad4823597a66129edb847f70a97de6c9d6d1f\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612254769023526",
"tags": [
"build_address:luci.chromium.ci/win-asan/17127",
"builder:win-asan",
"buildset:commit/git/5a3ad4823597a66129edb847f70a97de6c9d6d1f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5a3ad4823597a66129edb847f70a97de6c9d6d1f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021852122233521392",
"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/8856422060202165824/+/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": "1612254769023675",
"url": "https://ci.chromium.org/b/8856422060202165824",
"utcnow_ts": "1612254769289286"
}
}
[08:32:49.309] Task URL: https://ci.chromium.org/b/8856422060202165824
[08:32:49.309] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:2:0) after 1m0s
[08:33:49.322] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:2:0)
[08:33:49.322] Timer tick, asking Buildbucket for the build status
[08:33:49.548] Build 8856422060202165824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:33:49.548] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:3:0) after 1m0s
[08:34:49.567] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:3:0)
[08:34:49.567] Timer tick, asking Buildbucket for the build status
[08:34:49.956] Build 8856422060202165824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:34:49.956] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:4:0) after 1m0s
[08:35:49.975] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:4:0)
[08:35:49.975] Timer tick, asking Buildbucket for the build status
[08:35:50.059] Build 8856422060202165824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:35:50.059] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:5:0) after 1m0s
[08:36:17.151] Received PubSub notification, asking Buildbucket for the build status
[08:36:17.242] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:50.077] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:5:0)
[08:36:50.077] Timer tick, asking Buildbucket for the build status
[08:36:50.185] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:50.185] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:7:0) after 1m0s
[08:37:50.208] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:7:0)
[08:37:50.208] Timer tick, asking Buildbucket for the build status
[08:37:50.309] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:50.309] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:8:0) after 1m0s
[08:38:50.314] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:8:0)
[08:38:50.314] Timer tick, asking Buildbucket for the build status
[08:38:50.710] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:50.710] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:9:0) after 1m0s
[08:39:50.779] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:9:0)
[08:39:50.779] Timer tick, asking Buildbucket for the build status
[08:39:51.310] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:51.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:10:0) after 1m0s
[08:40:51.354] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:10:0)
[08:40:51.354] Timer tick, asking Buildbucket for the build status
[08:40:51.472] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:51.472] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:11:0) after 1m0s
[08:41:51.490] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:11:0)
[08:41:51.490] Timer tick, asking Buildbucket for the build status
[08:41:51.619] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:51.619] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:12:0) after 1m0s
[08:42:51.644] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:12:0)
[08:42:51.644] Timer tick, asking Buildbucket for the build status
[08:42:51.922] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:51.922] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:13:0) after 1m0s
[08:43:51.930] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:13:0)
[08:43:51.930] Timer tick, asking Buildbucket for the build status
[08:43:52.017] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:52.017] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:14:0) after 1m0s
[08:44:52.017] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:14:0)
[08:44:52.017] Timer tick, asking Buildbucket for the build status
[08:44:52.126] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:52.126] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:15:0) after 1m0s
[08:45:52.138] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:15:0)
[08:45:52.138] Timer tick, asking Buildbucket for the build status
[08:45:52.202] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:52.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:16:0) after 1m0s
[08:46:52.243] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:16:0)
[08:46:52.243] Timer tick, asking Buildbucket for the build status
[08:46:52.299] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:52.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:17:0) after 1m0s
[08:47:52.357] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:17:0)
[08:47:52.357] Timer tick, asking Buildbucket for the build status
[08:47:52.788] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:52.788] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:18:0) after 1m0s
[08:48:52.797] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:18:0)
[08:48:52.797] Timer tick, asking Buildbucket for the build status
[08:48:52.919] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:52.919] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:19:0) after 1m0s
[08:49:52.919] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:19:0)
[08:49:52.919] Timer tick, asking Buildbucket for the build status
[08:49:53.056] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:53.056] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:20:0) after 1m0s
[08:50:53.093] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:20:0)
[08:50:53.094] Timer tick, asking Buildbucket for the build status
[08:50:53.203] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:53.203] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:21:0) after 1m0s
[08:51:53.246] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:21:0)
[08:51:53.246] Timer tick, asking Buildbucket for the build status
[08:51:53.367] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:53.367] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:22:0) after 1m0s
[08:52:53.453] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:22:0)
[08:52:53.453] Timer tick, asking Buildbucket for the build status
[08:52:53.552] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:53.552] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:23:0) after 1m0s
[08:53:53.542] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:23:0)
[08:53:53.542] Timer tick, asking Buildbucket for the build status
[08:53:53.703] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:53.703] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:24:0) after 1m0s
[08:54:53.722] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:24:0)
[08:54:53.722] Timer tick, asking Buildbucket for the build status
[08:54:53.781] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:53.781] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:25:0) after 1m0s
[08:55:53.852] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:25:0)
[08:55:53.852] Timer tick, asking Buildbucket for the build status
[08:55:53.921] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:53.921] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:26:0) after 1m0s
[08:56:53.976] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:26:0)
[08:56:53.976] Timer tick, asking Buildbucket for the build status
[08:56:54.062] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:54.063] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:27:0) after 1m0s
[08:57:54.089] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:27:0)
[08:57:54.089] Timer tick, asking Buildbucket for the build status
[08:57:54.253] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:54.253] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:28:0) after 1m0s
[08:58:54.272] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:28:0)
[08:58:54.272] Timer tick, asking Buildbucket for the build status
[08:58:54.342] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:54.342] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:29:0) after 1m0s
[08:59:54.425] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:29:0)
[08:59:54.425] Timer tick, asking Buildbucket for the build status
[08:59:54.982] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:54.982] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:30:0) after 1m0s
[09:00:55.036] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:30:0)
[09:00:55.036] Timer tick, asking Buildbucket for the build status
[09:00:55.088] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:55.088] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:31:0) after 1m0s
[09:01:55.126] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:31:0)
[09:01:55.126] Timer tick, asking Buildbucket for the build status
[09:01:55.223] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:55.223] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:32:0) after 1m0s
[09:02:55.289] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:32:0)
[09:02:55.289] Timer tick, asking Buildbucket for the build status
[09:02:55.470] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:55.470] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:33:0) after 1m0s
[09:03:55.499] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:33:0)
[09:03:55.499] Timer tick, asking Buildbucket for the build status
[09:03:55.763] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:55.763] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:34:0) after 1m0s
[09:04:55.784] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:34:0)
[09:04:55.784] Timer tick, asking Buildbucket for the build status
[09:04:55.865] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:55.865] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:35:0) after 1m0s
[09:05:55.904] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:35:0)
[09:05:55.904] Timer tick, asking Buildbucket for the build status
[09:05:56.251] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:56.251] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:36:0) after 1m0s
[09:06:56.288] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:36:0)
[09:06:56.288] Timer tick, asking Buildbucket for the build status
[09:06:56.354] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:56.354] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:37:0) after 1m0s
[09:07:56.387] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:37:0)
[09:07:56.387] Timer tick, asking Buildbucket for the build status
[09:07:56.493] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:56.493] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:38:0) after 1m0s
[09:08:56.523] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:38:0)
[09:08:56.523] Timer tick, asking Buildbucket for the build status
[09:08:56.992] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:56.992] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:39:0) after 1m0s
[09:09:57.027] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:39:0)
[09:09:57.027] Timer tick, asking Buildbucket for the build status
[09:09:57.089] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:57.089] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:40:0) after 1m0s
[09:10:57.251] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:40:0)
[09:10:57.251] Timer tick, asking Buildbucket for the build status
[09:10:57.318] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:57.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:41:0) after 1m0s
[09:11:57.318] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:41:0)
[09:11:57.318] Timer tick, asking Buildbucket for the build status
[09:11:57.387] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:57.387] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:42:0) after 1m0s
[09:12:57.411] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:42:0)
[09:12:57.411] Timer tick, asking Buildbucket for the build status
[09:12:57.504] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:57.504] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:43:0) after 1m0s
[09:13:57.516] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:43:0)
[09:13:57.516] Timer tick, asking Buildbucket for the build status
[09:13:57.612] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:57.612] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:44:0) after 1m0s
[09:14:57.610] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:44:0)
[09:14:57.610] Timer tick, asking Buildbucket for the build status
[09:14:57.680] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:57.680] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:45:0) after 1m0s
[09:15:57.711] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:45:0)
[09:15:57.711] Timer tick, asking Buildbucket for the build status
[09:15:57.771] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:57.771] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:46:0) after 1m0s
[09:16:57.778] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:46:0)
[09:16:57.778] Timer tick, asking Buildbucket for the build status
[09:16:58.148] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:58.148] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:47:0) after 1m0s
[09:17:58.196] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:47:0)
[09:17:58.196] Timer tick, asking Buildbucket for the build status
[09:17:58.323] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:58.323] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:48:0) after 1m0s
[09:18:58.348] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:48:0)
[09:18:58.348] Timer tick, asking Buildbucket for the build status
[09:18:58.526] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:58.527] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:49:0) after 1m0s
[09:19:58.590] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:49:0)
[09:19:58.590] Timer tick, asking Buildbucket for the build status
[09:19:58.768] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:58.768] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:50:0) after 1m0s
[09:20:58.807] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:50:0)
[09:20:58.807] Timer tick, asking Buildbucket for the build status
[09:20:58.869] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:58.869] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:51:0) after 1m0s
[09:21:58.888] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:51:0)
[09:21:58.888] Timer tick, asking Buildbucket for the build status
[09:21:58.962] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:58.963] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:52:0) after 1m0s
[09:22:58.986] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:52:0)
[09:22:58.986] Timer tick, asking Buildbucket for the build status
[09:22:59.092] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:59.092] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:53:0) after 1m0s
[09:23:59.122] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:53:0)
[09:23:59.122] Timer tick, asking Buildbucket for the build status
[09:23:59.192] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:59.192] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:54:0) after 1m0s
[09:24:59.315] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:54:0)
[09:24:59.315] Timer tick, asking Buildbucket for the build status
[09:24:59.421] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:59.421] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:55:0) after 1m0s
[09:25:59.472] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:55:0)
[09:25:59.472] Timer tick, asking Buildbucket for the build status
[09:25:59.567] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:59.567] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:56:0) after 1m0s
[09:26:59.660] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:56:0)
[09:26:59.660] Timer tick, asking Buildbucket for the build status
[09:26:59.742] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:59.742] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:57:0) after 1m0s
[09:27:59.837] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:57:0)
[09:27:59.837] Timer tick, asking Buildbucket for the build status
[09:28:00.066] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:00.066] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:58:0) after 1m0s
[09:29:00.740] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:58:0)
[09:29:00.740] Timer tick, asking Buildbucket for the build status
[09:29:00.941] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:00.941] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:59:0) after 1m0s
[09:30:01.261] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:59:0)
[09:30:01.261] Timer tick, asking Buildbucket for the build status
[09:30:01.324] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:01.324] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:60:0) after 1m0s
[09:31:01.360] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:60:0)
[09:31:01.360] Timer tick, asking Buildbucket for the build status
[09:31:01.603] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:01.603] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:61:0) after 1m0s
[09:32:01.689] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:61:0)
[09:32:01.689] Timer tick, asking Buildbucket for the build status
[09:32:01.912] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:01.912] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:62:0) after 1m0s
[09:33:02.023] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:62:0)
[09:33:02.023] Timer tick, asking Buildbucket for the build status
[09:33:02.135] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:02.135] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:63:0) after 1m0s
[09:34:02.160] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:63:0)
[09:34:02.160] Timer tick, asking Buildbucket for the build status
[09:34:02.296] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:02.296] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:64:0) after 1m0s
[09:35:02.387] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:64:0)
[09:35:02.387] Timer tick, asking Buildbucket for the build status
[09:35:02.457] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:02.457] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:65:0) after 1m0s
[09:36:02.486] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:65:0)
[09:36:02.486] Timer tick, asking Buildbucket for the build status
[09:36:02.638] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:02.638] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:66:0) after 1m0s
[09:37:02.660] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:66:0)
[09:37:02.660] Timer tick, asking Buildbucket for the build status
[09:37:02.830] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:02.830] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:67:0) after 1m0s
[09:38:02.978] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:67:0)
[09:38:02.978] Timer tick, asking Buildbucket for the build status
[09:38:03.303] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:03.303] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:68:0) after 1m0s
[09:39:03.327] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:68:0)
[09:39:03.327] Timer tick, asking Buildbucket for the build status
[09:39:03.445] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:03.445] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:69:0) after 1m0s
[09:40:03.487] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:69:0)
[09:40:03.488] Timer tick, asking Buildbucket for the build status
[09:40:03.623] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:03.623] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:70:0) after 1m0s
[09:41:03.648] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:70:0)
[09:41:03.648] Timer tick, asking Buildbucket for the build status
[09:41:03.846] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:03.846] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:71:0) after 1m0s
[09:42:03.951] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:71:0)
[09:42:03.951] Timer tick, asking Buildbucket for the build status
[09:42:04.109] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:04.109] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:72:0) after 1m0s
[09:43:04.168] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:72:0)
[09:43:04.168] Timer tick, asking Buildbucket for the build status
[09:43:04.311] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:04.311] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:73:0) after 1m0s
[09:44:04.336] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:73:0)
[09:44:04.336] Timer tick, asking Buildbucket for the build status
[09:44:04.846] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:04.846] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:74:0) after 1m0s
[09:45:04.970] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:74:0)
[09:45:04.970] Timer tick, asking Buildbucket for the build status
[09:45:05.099] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:05.099] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:75:0) after 1m0s
[09:46:05.128] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:75:0)
[09:46:05.128] Timer tick, asking Buildbucket for the build status
[09:46:05.273] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:05.273] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:76:0) after 1m0s
[09:47:05.359] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:76:0)
[09:47:05.359] Timer tick, asking Buildbucket for the build status
[09:47:05.505] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:05.505] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:77:0) after 1m0s
[09:48:05.622] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:77:0)
[09:48:05.622] Timer tick, asking Buildbucket for the build status
[09:48:05.735] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:05.735] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:78:0) after 1m0s
[09:49:05.810] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:78:0)
[09:49:05.810] Timer tick, asking Buildbucket for the build status
[09:49:05.917] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:05.917] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:79:0) after 1m0s
[09:50:05.992] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:79:0)
[09:50:05.992] Timer tick, asking Buildbucket for the build status
[09:50:06.139] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:06.139] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:80:0) after 1m0s
[09:51:06.114] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:80:0)
[09:51:06.114] Timer tick, asking Buildbucket for the build status
[09:51:06.248] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:06.249] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:81:0) after 1m0s
[09:52:06.262] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:81:0)
[09:52:06.262] Timer tick, asking Buildbucket for the build status
[09:52:06.361] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:06.361] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:82:0) after 1m0s
[09:53:06.351] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:82:0)
[09:53:06.351] Timer tick, asking Buildbucket for the build status
[09:53:06.510] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:06.510] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:83:0) after 1m0s
[09:54:06.524] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:83:0)
[09:54:06.524] Timer tick, asking Buildbucket for the build status
[09:54:06.587] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:06.587] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:84:0) after 1m0s
[09:55:06.619] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:84:0)
[09:55:06.619] Timer tick, asking Buildbucket for the build status
[09:55:06.689] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:06.689] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:85:0) after 1m0s
[09:56:06.717] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:85:0)
[09:56:06.717] Timer tick, asking Buildbucket for the build status
[09:56:06.807] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:06.807] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:86:0) after 1m0s
[09:57:06.952] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:86:0)
[09:57:06.952] Timer tick, asking Buildbucket for the build status
[09:57:07.058] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:07.058] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:87:0) after 1m0s
[09:58:07.086] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:87:0)
[09:58:07.086] Timer tick, asking Buildbucket for the build status
[09:58:07.208] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:07.208] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:88:0) after 1m0s
[09:59:07.308] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:88:0)
[09:59:07.309] Timer tick, asking Buildbucket for the build status
[09:59:07.662] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:07.662] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:89:0) after 1m0s
[10:00:07.726] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:89:0)
[10:00:07.726] Timer tick, asking Buildbucket for the build status
[10:00:07.866] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:07.866] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:90:0) after 1m0s
[10:01:08.721] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:90:0)
[10:01:08.721] Timer tick, asking Buildbucket for the build status
[10:01:08.799] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:08.799] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:91:0) after 1m0s
[10:02:08.956] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:91:0)
[10:02:08.956] Timer tick, asking Buildbucket for the build status
[10:02:09.041] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:09.041] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:92:0) after 1m0s
[10:03:09.165] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:92:0)
[10:03:09.166] Timer tick, asking Buildbucket for the build status
[10:03:09.328] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:09.328] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:93:0) after 1m0s
[10:04:09.380] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:93:0)
[10:04:09.380] Timer tick, asking Buildbucket for the build status
[10:04:09.537] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:09.537] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:94:0) after 1m0s
[10:05:09.516] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:94:0)
[10:05:09.516] Timer tick, asking Buildbucket for the build status
[10:05:09.612] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:09.612] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:95:0) after 1m0s
[10:06:09.636] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:95:0)
[10:06:09.636] Timer tick, asking Buildbucket for the build status
[10:06:09.957] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:09.957] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:96:0) after 1m0s
[10:07:10.010] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:96:0)
[10:07:10.010] Timer tick, asking Buildbucket for the build status
[10:07:10.409] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:10.410] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:97:0) after 1m0s
[10:08:10.504] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:97:0)
[10:08:10.504] Timer tick, asking Buildbucket for the build status
[10:08:10.641] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:10.641] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:98:0) after 1m0s
[10:09:10.734] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:98:0)
[10:09:10.734] Timer tick, asking Buildbucket for the build status
[10:09:10.814] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:10.814] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:99:0) after 1m0s
[10:10:10.848] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:99:0)
[10:10:10.848] Timer tick, asking Buildbucket for the build status
[10:10:10.961] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:10.961] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:100:0) after 1m0s
[10:11:10.985] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:100:0)
[10:11:10.985] Timer tick, asking Buildbucket for the build status
[10:11:11.115] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:11.115] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:101:0) after 1m0s
[10:12:11.247] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:101:0)
[10:12:11.247] Timer tick, asking Buildbucket for the build status
[10:12:11.335] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:11.335] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:102:0) after 1m0s
[10:13:11.355] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:102:0)
[10:13:11.355] Timer tick, asking Buildbucket for the build status
[10:13:11.462] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:11.462] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:103:0) after 1m0s
[10:14:11.638] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:103:0)
[10:14:11.638] Timer tick, asking Buildbucket for the build status
[10:14:11.820] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:11.820] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:104:0) after 1m0s
[10:15:11.856] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:104:0)
[10:15:11.856] Timer tick, asking Buildbucket for the build status
[10:15:11.985] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:11.985] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:105:0) after 1m0s
[10:16:12.059] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:105:0)
[10:16:12.059] Timer tick, asking Buildbucket for the build status
[10:16:12.304] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:12.304] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:106:0) after 1m0s
[10:17:12.337] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:106:0)
[10:17:12.337] Timer tick, asking Buildbucket for the build status
[10:17:12.963] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:12.963] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:107:0) after 1m0s
[10:18:13.082] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:107:0)
[10:18:13.082] Timer tick, asking Buildbucket for the build status
[10:18:13.198] Build 8856422060202165824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:13.198] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021852122233521392:108:0) after 1m0s
[10:18:16.992] Received PubSub notification, asking Buildbucket for the build status
[10:18:17.071] Build 8856422060202165824: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[10:18:17.071] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612261095967160",
"created_by": "project:chromium",
"created_ts": "1612254768139790",
"id": "8856422060202165824",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5a3ad4823597a66129edb847f70a97de6c9d6d1f\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 500.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"5a3ad4823597a66129edb847f70a97de6c9d6d1f\", \"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-8-uuz6\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17127, \"clang_revision\": \"llvmorg-12-init-17251-g6de48655-1\", \"got_angle_revision\": \"7e81056a54691bea83cbea37cb6a94dadb7c4bda\", \"got_dawn_revision\": \"9a2174a37cfdc771a9c7a494e6fb773c541bd941\", \"got_nacl_revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"got_revision\": \"5a3ad4823597a66129edb847f70a97de6c9d6d1f\", \"got_revision_cp\": \"refs/heads/master@{#849507}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"51045556acd0fe0a1b687ef00725a57f0f19a91b\", \"got_v8_revision\": \"079f7c53751ac589cf05880522b3fa2fcb5b5645\", \"got_v8_revision_cp\": \"refs/heads/9.0.109@{#1}\", \"got_webrtc_revision\": \"e7c79fd3d66e0f6c54c027f880a8b1682ebae58a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33124}\", \"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\": \"5a3ad4823597a66129edb847f70a97de6c9d6d1f\", \"swarm_hashes_refs/heads/master(at){#849507}_without_patch\": {\"absl_hardening_tests\": \"1726408473e9e22f85bc6812521f41950f255d8d\", \"accessibility_unittests\": \"e115756403c39ddbf90a0132ec44446c0d4ebc25\", \"angle_unittests\": \"ee0b23d7277055a7b8e76770ccb710e296e894e9\", \"app_shell_unittests\": \"32669c79a94bd94e532136c87d9b57a0eb5b261d\", \"aura_unittests\": \"041084ebe6573eac5e09b31dd9aa2d5da6ae38f5\", \"base_unittests\": \"7f4f2668dc7121d3c522b6ee67b0781b17bd935a\", \"base_util_unittests\": \"d911f30504db7f149eec9de9565facfcfd7ad9fc\", \"blink_common_unittests\": \"6336d59775e399ef940730e7dc63ab0785722168\", \"blink_fuzzer_unittests\": \"3166996fb4b477e3cb50defd6cd6059d73c5aba8\", \"blink_heap_unittests\": \"c4f7d2de90ec77d373aa162de0adfb31ed75ce40\", \"blink_platform_unittests\": \"89f04cbc15ef1297bddf4fdfd1cc130400e2e480\", \"blink_unittests\": \"7e40d550132f77d367f6f9341c2debf702d61df5\", \"boringssl_crypto_tests\": \"edf2e10614e4d7fbabb6e2b4e56a2630204c35a1\", \"boringssl_ssl_tests\": \"f061c32389acaf2905500266add5eb654ecd8ea7\", \"browser_switcher_bho_unittests\": \"88ab1df89fcd45ad1435f45abbc651a56ffdf7a8\", \"browser_tests\": \"f8c19ae10bc1e0376fbc31239f4a5399c52ff86c\", \"capture_unittests\": \"6568841db3d5302f0b9bb1b8c85ac4544569f979\", \"cast_unittests\": \"a5a86dfd5c32f1c4a735dc9db8de2a29dc3f94c1\", \"cc_unittests\": \"f15bc54afd60c5cb26cee58cd7d4a722e3ccbf10\", \"chrome_app_unittests\": \"120872368c0893bbbbf35d2f99b204db8544a4cd\", \"chrome_cleaner_unittests\": \"1cf5fef4db3a6e9dd8c629535c7372fdc1c4a58b\", \"chrome_elf_unittests\": \"2378b7dde6a91de3a9974103a594d811f2f73e6b\", \"chromedriver_unittests\": \"22bea78c980669bede651904c35c542074b60d37\", \"components_browsertests\": \"1539179cd79d7176d4086e68d9015d9627544f3a\", \"components_unittests\": \"3952333129cec0b57800f81acd3159249f1c48b5\", \"compositor_unittests\": \"380bcd5ea3f30cc8c9971da95d863bb6c8af53a3\", \"content_browsertests\": \"26a7c2926c05335b709085eee7a724e4b712779e\", \"content_unittests\": \"37376720ea2f35c02c8aed6e0a899e0eee81b8d6\", \"courgette_unittests\": \"9560636e129ef54e040b686330bc90577209603c\", \"crashpad_tests\": \"b66e6e1b90acab1eba13d89e7e792d7d638931f1\", \"cronet_tests\": \"9641c5f2e780ea71250ecdc98d95c220c202a5a5\", \"cronet_unittests\": \"7adb1876b5681a4d22caef8a43f2baddde4adc11\", \"crypto_unittests\": \"be3d8d8ae68e229a11699aa2ce6ffff67d5a2891\", \"delayloads_unittests\": \"c8d2f3c96d3199b6822332e8a59de7855b0088d2\", \"device_unittests\": \"8ff16679ad2b7199787e1d1fe1aac7df01582779\", \"display_unittests\": \"f5f058e768b38d3460868d692ff9b0de5abe84ab\", \"elevation_service_unittests\": \"ba7dd029ee44fc58016f2710dd1c314a8e4c436f\", \"events_unittests\": \"46e5aebd2163fcb3650eae961ae1d2a045e2dcff\", \"extensions_browsertests\": \"e3087afb389d90344141f7c36209cc8d3cf7f305\", \"extensions_unittests\": \"df0bd8f47155ed2e2128962057528188174aedae\", \"filesystem_service_unittests\": \"180fea9c2ab961e538acb24dd742fcb637dc521f\", \"gcm_unit_tests\": \"8c001c417b8bd006f00d2871addb0c817ec77be5\", \"gcp_unittests\": \"ec3407b547825dae011de04c22ad60bbca56e5e1\", \"gfx_unittests\": \"dd9f04df439be60b3089138703b4dfdf22df566d\", \"gin_unittests\": \"c8a8d34983fe69b2ee6b73dfee03403e5a34a2f5\", \"google_apis_unittests\": \"f89570f503bdfd8ad3c33b47d2549a86065579db\", \"gpu_unittests\": \"5051edfd43a361cabcb1e8b4f83f1873c78a6c62\", \"gwp_asan_unittests\": \"274019a9a50d17638738f5f56172318033a1766e\", \"headless_browsertests\": \"3586f00e08700cb099fc4d2bf826d840c9418951\", \"headless_unittests\": \"9d51d9aab56ab6c34995789501215a23acb78665\", \"install_static_unittests\": \"de4f61419988d00ee482297f926de2016af7cac1\", \"installer_util_unittests\": \"22e040564b0ed99ff25cad4fae4b60ee0a19282d\", \"interactive_ui_tests\": \"7f9c39d1a84d3f7cfc99478f64b164143f75c34f\", \"ipc_tests\": \"19c57755c8afe6f27ffa55472e9c8cd5169964fd\", \"jingle_unittests\": \"c0b4dd84c6ab9e301b7cff23e897df8f6478f67e\", \"latency_unittests\": \"217579a3dbe455c1d73322528e10aa5beb6c5d1f\", \"libjingle_xmpp_unittests\": \"fb81887492bc2124d09936f957193c29d24a0e47\", \"liburlpattern_unittests\": \"8a6519df76d7b835e16a0bb23c4b6aecb08fdf2c\", \"media_blink_unittests\": \"d124e3bcffa73996a138eeb5c27138cb8100672a\", \"media_unittests\": \"49492c0dc51674c03eda50b1c779d0c70877ea0e\", \"message_center_unittests\": \"c2f86c78d96354f6d9dc9e7305d3afb8b507922e\", \"midi_unittests\": \"a7aba47ee99e6c0800c6366d74415a79de889132\", \"mojo_core_unittests\": \"e981b3f2ed86ee1e0a459978c20571a19920bd68\", \"mojo_unittests\": \"0e6184c12b4657988f00f875dd8db8e7410761df\", \"nacl_loader_unittests\": \"72dd8712cae19355b64dfdaf87640d3230de00f2\", \"native_theme_unittests\": \"93ef0a339a4dbb1c4b723cc8c03c635dc0bb660c\", \"net_unittests\": \"a953ccb7254aa97952ca7a1b8d6d8bb9e743b71e\", \"notification_helper_unittests\": \"dbdac1448f28d371fd30b082baf738573a6542ed\", \"pdf_unittests\": \"73c538d41ba25ac628519069850ec1dfde493755\", \"perfetto_unittests\": \"8625b7542f05d72eaf2638b55a5d8e03b9758226\", \"ppapi_unittests\": \"8e5a29fa5e821ef75ed498d69e2cb766f4cca652\", \"printing_unittests\": \"7b0b2e2ce053cc2b2d09ca3a07f4cac7613a8fb2\", \"remoting_unittests\": \"6586b5aeccb4b0a8d60df6d1e9da0d9014123db9\", \"sbox_integration_tests\": \"21ee6a037cb7da1ff0feeb741ff94595c613d372\", \"sbox_unittests\": \"0a42b578c4fa4815e44f943630a5b76b94a53141\", \"sbox_validation_tests\": \"03106d5ea08dfff43ceab6bd769ae52e28ac65a9\", \"service_manager_unittests\": \"1f12029dd7b3fd2a7b19d80e70c6deb8e2e38582\", \"services_unittests\": \"97ce0eee2d5283918668f76b908c51634a6386bc\", \"setup_unittests\": \"755b6e7f1370dabae205d42c7e73008802b4c2ca\", \"shell_dialogs_unittests\": \"662643b64c1fe3f3626622c09b2b37ddec3a50c5\", \"skia_unittests\": \"3588074ade387fd4d5fad2bd3ec1988597cfc50e\", \"snapshot_unittests\": \"1ca3855dfb874f5335123ae0911aec512edba6ad\", \"sql_unittests\": \"f681d2cd85d2e0543860113ad252300499a2a9ef\", \"storage_unittests\": \"a2fdd117f24b03e5aadd7bc70d67c2ec902be418\", \"sync_integration_tests\": \"247a62ea96d57dd4063a9b7981810854ae92d3e1\", \"traffic_annotation_auditor_unittests\": \"cf12724f76b0661d542c03cbb7b15d391c2d70aa\", \"ui_base_unittests\": \"f896c6e1c67f0487bc01bd98433f28994e7d017d\", \"ui_touch_selection_unittests\": \"8a677c3eadeb748c6571804c9f3d609efa3b2c20\", \"unit_tests\": \"edf2ee614d1c7fd40360453278567b1809a476b4\", \"updater_tests\": \"f814c9a40404b1dabba090b73505262239a97cfa\", \"url_unittests\": \"e8f33a5551472cf3b8fd69209c5a77c316906e01\", \"views_unittests\": \"b162efa04407cdadac65a0671f4504d67875e7dc\", \"viz_unittests\": \"2a14d3cfa78b5ad5d9ef8614af3bf22ff17a1518\", \"vr_common_unittests\": \"307d416d8cd5e95c0f29c2ddf8e0f004dbc83ab2\", \"vr_pixeltests\": \"8e0a881997f22a2128604e63d3cb3d052ab78e90\", \"weblayer_browsertests\": \"9f5f4b70c1524f4113a6182637e176cea8baeb79\", \"weblayer_unittests\": \"077cfc7cce9763066dd74fa040468c87313356c2\", \"wm_unittests\": \"1979d038c09b7917d29ab106d9178d88c2ce876b\", \"wtf_unittests\": \"c8ed3f38f0c57bf209df15d6a9dd1983d200a1fd\", \"zlib_unittests\": \"9345de151e6e1e5571f055e4fb7eec6965f80741\", \"zucchini_unittests\": \"d571109f4423f275add2933062286100dfb7b720\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_02613bacee8cd1c41158238da4ce7615021ecb28a4b52e6029f89cb601af0790_v2\", \"builder_27a86a15ec8feb386a5b02e9d1f544a49194bc134f7348519b0bd6cd38b20371_v2\", \"builder_a4ee364c4b5996d1649f16c77d7186222b75eb5b75762fbbe3baeec97aac12d9_v2\", \"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"builder_f77c1c08af9d0a4522ad60c9181aa925a8ae87fd7ec8443f284fb40f3b6bc27b_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-8-uuz6\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5673-2413400\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1612254976543843",
"status": "COMPLETED",
"status_changed_ts": "1612261096434510",
"tags": [
"build_address:luci.chromium.ci/win-asan/17127",
"builder:win-asan",
"buildset:commit/git/5a3ad4823597a66129edb847f70a97de6c9d6d1f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5a3ad4823597a66129edb847f70a97de6c9d6d1f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021852122233521392",
"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/8856422060202165824/+/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:517ab0f862ff2e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1612261096443861",
"url": "https://ci.chromium.org/b/8856422060202165824",
"utcnow_ts": "1612261097060866"
}
}
[10:18:17.071] Invocation finished in 1h45m30.252412504s with status SUCCEEDED