[18:36:36.539] New invocation is queued and will start shortly
[18:36:37.697] Starting the invocation (attempt 1)
[18:36:37.769] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:36:37.769] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:36:37.769] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019911596338789552",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"9764750545a7b1c7b982c3bb8037dbde22e111d7\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9019911596338789552",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/9764750545a7b1c7b982c3bb8037dbde22e111d7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9764750545a7b1c7b982c3bb8037dbde22e111d7",
"gitiles_ref:refs/heads/master"
]
}
[18:36:39.014] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614105397898379",
"id": "8854481534251739872",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9764750545a7b1c7b982c3bb8037dbde22e111d7\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614105398647977",
"tags": [
"build_address:luci.chromium.ci/win-asan/17495",
"builder:win-asan",
"buildset:commit/git/9764750545a7b1c7b982c3bb8037dbde22e111d7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9764750545a7b1c7b982c3bb8037dbde22e111d7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019911596338789552",
"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/8854481534251739872/+/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": "1614105398648088",
"url": "https://ci.chromium.org/b/8854481534251739872",
"utcnow_ts": "1614105399004024"
}
}
[18:36:39.014] Task URL: https://ci.chromium.org/b/8854481534251739872
[18:36:39.014] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:2:0) after 1m0s
[18:37:39.106] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:2:0)
[18:37:39.106] Timer tick, asking Buildbucket for the build status
[18:37:39.183] Build 8854481534251739872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:37:39.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:3:0) after 1m0s
[18:38:39.298] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:3:0)
[18:38:39.298] Timer tick, asking Buildbucket for the build status
[18:38:39.368] Build 8854481534251739872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:38:39.368] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:4:0) after 1m0s
[18:39:11.175] Received PubSub notification, asking Buildbucket for the build status
[18:39:11.281] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:39.431] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:4:0)
[18:39:39.431] Timer tick, asking Buildbucket for the build status
[18:39:39.565] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:39.565] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:6:0) after 1m0s
[18:40:39.707] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:6:0)
[18:40:39.707] Timer tick, asking Buildbucket for the build status
[18:40:39.911] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:39.911] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:7:0) after 1m0s
[18:41:39.877] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:7:0)
[18:41:39.877] Timer tick, asking Buildbucket for the build status
[18:41:39.951] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:39.951] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:8:0) after 1m0s
[18:42:40.030] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:8:0)
[18:42:40.030] Timer tick, asking Buildbucket for the build status
[18:42:40.152] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:40.152] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:9:0) after 1m0s
[18:43:40.152] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:9:0)
[18:43:40.152] Timer tick, asking Buildbucket for the build status
[18:43:40.241] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:40.241] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:10:0) after 1m0s
[18:44:40.258] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:10:0)
[18:44:40.258] Timer tick, asking Buildbucket for the build status
[18:44:40.334] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:40.334] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:11:0) after 1m0s
[18:45:40.359] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:11:0)
[18:45:40.359] Timer tick, asking Buildbucket for the build status
[18:45:40.525] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:40.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:12:0) after 1m0s
[18:46:40.521] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:12:0)
[18:46:40.521] Timer tick, asking Buildbucket for the build status
[18:46:40.743] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:40.743] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:13:0) after 1m0s
[18:47:40.828] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:13:0)
[18:47:40.828] Timer tick, asking Buildbucket for the build status
[18:47:40.924] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:40.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:14:0) after 1m0s
[18:48:40.982] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:14:0)
[18:48:40.982] Timer tick, asking Buildbucket for the build status
[18:48:41.095] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:41.095] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:15:0) after 1m0s
[18:49:41.154] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:15:0)
[18:49:41.154] Timer tick, asking Buildbucket for the build status
[18:49:41.230] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:41.230] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:16:0) after 1m0s
[18:50:41.260] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:16:0)
[18:50:41.260] Timer tick, asking Buildbucket for the build status
[18:50:41.565] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:41.565] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:17:0) after 1m0s
[18:51:41.623] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:17:0)
[18:51:41.623] Timer tick, asking Buildbucket for the build status
[18:51:41.948] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:41.948] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:18:0) after 1m0s
[18:52:41.971] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:18:0)
[18:52:41.971] Timer tick, asking Buildbucket for the build status
[18:52:42.731] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:42.731] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:19:0) after 1m0s
[18:53:42.876] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:19:0)
[18:53:42.876] Timer tick, asking Buildbucket for the build status
[18:53:42.966] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:42.966] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:20:0) after 1m0s
[18:54:43.044] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:20:0)
[18:54:43.044] Timer tick, asking Buildbucket for the build status
[18:54:45.875] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:45.875] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:21:0) after 1m0s
[18:55:45.899] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:21:0)
[18:55:45.899] Timer tick, asking Buildbucket for the build status
[18:55:46.164] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:46.164] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:22:0) after 1m0s
[18:56:46.185] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:22:0)
[18:56:46.185] Timer tick, asking Buildbucket for the build status
[18:56:46.546] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:46.546] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:23:0) after 1m0s
[18:57:46.534] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:23:0)
[18:57:46.534] Timer tick, asking Buildbucket for the build status
[18:57:46.627] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:46.627] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:24:0) after 1m0s
[18:58:46.632] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:24:0)
[18:58:46.632] Timer tick, asking Buildbucket for the build status
[18:58:46.719] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:46.719] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:25:0) after 1m0s
[18:59:46.737] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:25:0)
[18:59:46.737] Timer tick, asking Buildbucket for the build status
[18:59:47.015] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:47.015] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:26:0) after 1m0s
[19:00:47.044] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:26:0)
[19:00:47.044] Timer tick, asking Buildbucket for the build status
[19:00:47.344] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:47.344] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:27:0) after 1m0s
[19:01:47.360] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:27:0)
[19:01:47.360] Timer tick, asking Buildbucket for the build status
[19:01:47.705] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:47.705] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:28:0) after 1m0s
[19:02:47.736] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:28:0)
[19:02:47.736] Timer tick, asking Buildbucket for the build status
[19:02:47.829] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:47.829] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:29:0) after 1m0s
[19:03:47.847] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:29:0)
[19:03:47.847] Timer tick, asking Buildbucket for the build status
[19:03:48.162] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:48.162] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:30:0) after 1m0s
[19:04:48.186] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:30:0)
[19:04:48.186] Timer tick, asking Buildbucket for the build status
[19:04:48.351] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:48.351] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:31:0) after 1m0s
[19:05:48.347] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:31:0)
[19:05:48.347] Timer tick, asking Buildbucket for the build status
[19:05:48.430] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:48.430] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:32:0) after 1m0s
[19:06:48.462] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:32:0)
[19:06:48.463] Timer tick, asking Buildbucket for the build status
[19:06:48.606] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:48.606] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:33:0) after 1m0s
[19:07:48.630] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:33:0)
[19:07:48.630] Timer tick, asking Buildbucket for the build status
[19:07:48.708] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:48.708] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:34:0) after 1m0s
[19:08:48.728] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:34:0)
[19:08:48.728] Timer tick, asking Buildbucket for the build status
[19:08:48.805] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:48.805] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:35:0) after 1m0s
[19:09:48.885] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:35:0)
[19:09:48.885] Timer tick, asking Buildbucket for the build status
[19:09:49.082] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:49.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:36:0) after 1m0s
[19:10:49.130] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:36:0)
[19:10:49.130] Timer tick, asking Buildbucket for the build status
[19:10:49.201] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:49.201] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:37:0) after 1m0s
[19:11:49.223] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:37:0)
[19:11:49.223] Timer tick, asking Buildbucket for the build status
[19:11:49.348] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:49.348] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:38:0) after 1m0s
[19:12:49.368] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:38:0)
[19:12:49.368] Timer tick, asking Buildbucket for the build status
[19:12:49.521] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:49.521] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:39:0) after 1m0s
[19:13:49.589] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:39:0)
[19:13:49.589] Timer tick, asking Buildbucket for the build status
[19:13:49.731] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:49.731] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:40:0) after 1m0s
[19:14:49.818] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:40:0)
[19:14:49.818] Timer tick, asking Buildbucket for the build status
[19:14:49.907] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:49.907] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:41:0) after 1m0s
[19:15:49.909] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:41:0)
[19:15:49.909] Timer tick, asking Buildbucket for the build status
[19:15:49.983] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:49.983] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:42:0) after 1m0s
[19:16:50.020] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:42:0)
[19:16:50.020] Timer tick, asking Buildbucket for the build status
[19:16:50.119] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:50.119] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:43:0) after 1m0s
[19:17:50.147] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:43:0)
[19:17:50.147] Timer tick, asking Buildbucket for the build status
[19:17:50.253] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:50.253] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:44:0) after 1m0s
[19:18:50.312] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:44:0)
[19:18:50.312] Timer tick, asking Buildbucket for the build status
[19:18:50.439] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:50.439] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:45:0) after 1m0s
[19:19:50.476] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:45:0)
[19:19:50.476] Timer tick, asking Buildbucket for the build status
[19:19:50.691] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:50.691] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:46:0) after 1m0s
[19:20:50.700] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:46:0)
[19:20:50.700] Timer tick, asking Buildbucket for the build status
[19:20:50.835] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:50.835] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:47:0) after 1m0s
[19:21:50.863] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:47:0)
[19:21:50.863] Timer tick, asking Buildbucket for the build status
[19:21:51.247] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:51.247] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:48:0) after 1m0s
[19:22:51.282] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:48:0)
[19:22:51.282] Timer tick, asking Buildbucket for the build status
[19:22:51.376] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:51.376] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:49:0) after 1m0s
[19:23:51.414] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:49:0)
[19:23:51.414] Timer tick, asking Buildbucket for the build status
[19:23:51.527] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:51.527] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:50:0) after 1m0s
[19:24:51.557] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:50:0)
[19:24:51.557] Timer tick, asking Buildbucket for the build status
[19:24:51.628] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:51.628] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:51:0) after 1m0s
[19:25:51.676] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:51:0)
[19:25:51.676] Timer tick, asking Buildbucket for the build status
[19:25:51.766] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:51.766] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:52:0) after 1m0s
[19:26:51.753] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:52:0)
[19:26:51.753] Timer tick, asking Buildbucket for the build status
[19:26:51.844] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:51.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:53:0) after 1m0s
[19:27:51.915] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:53:0)
[19:27:51.915] Timer tick, asking Buildbucket for the build status
[19:27:51.977] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:51.977] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:54:0) after 1m0s
[19:28:52.074] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:54:0)
[19:28:52.074] Timer tick, asking Buildbucket for the build status
[19:28:52.146] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:52.146] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:55:0) after 1m0s
[19:29:52.207] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:55:0)
[19:29:52.207] Timer tick, asking Buildbucket for the build status
[19:29:52.281] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:52.281] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:56:0) after 1m0s
[19:30:53.209] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:56:0)
[19:30:53.209] Timer tick, asking Buildbucket for the build status
[19:30:53.280] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:53.280] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:57:0) after 1m0s
[19:31:53.564] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:57:0)
[19:31:53.564] Timer tick, asking Buildbucket for the build status
[19:31:53.671] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:53.672] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:58:0) after 1m0s
[19:32:53.733] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:58:0)
[19:32:53.733] Timer tick, asking Buildbucket for the build status
[19:32:53.833] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:53.833] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:59:0) after 1m0s
[19:33:53.891] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:59:0)
[19:33:53.891] Timer tick, asking Buildbucket for the build status
[19:33:54.299] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:54.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:60:0) after 1m0s
[19:34:54.323] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:60:0)
[19:34:54.323] Timer tick, asking Buildbucket for the build status
[19:34:54.612] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:54.612] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:61:0) after 1m0s
[19:35:54.655] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:61:0)
[19:35:54.655] Timer tick, asking Buildbucket for the build status
[19:35:54.741] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:54.741] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:62:0) after 1m0s
[19:36:54.836] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:62:0)
[19:36:54.836] Timer tick, asking Buildbucket for the build status
[19:36:54.977] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:54.977] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:63:0) after 1m0s
[19:37:55.203] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:63:0)
[19:37:55.203] Timer tick, asking Buildbucket for the build status
[19:37:55.296] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:55.296] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:64:0) after 1m0s
[19:38:55.387] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:64:0)
[19:38:55.387] Timer tick, asking Buildbucket for the build status
[19:38:55.453] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:55.453] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:65:0) after 1m0s
[19:39:55.502] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:65:0)
[19:39:55.503] Timer tick, asking Buildbucket for the build status
[19:39:55.603] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:55.603] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:66:0) after 1m0s
[19:40:55.707] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:66:0)
[19:40:55.707] Timer tick, asking Buildbucket for the build status
[19:40:56.098] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:56.099] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:67:0) after 1m0s
[19:41:56.123] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:67:0)
[19:41:56.123] Timer tick, asking Buildbucket for the build status
[19:41:56.259] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:56.259] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:68:0) after 1m0s
[19:42:56.292] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:68:0)
[19:42:56.292] Timer tick, asking Buildbucket for the build status
[19:42:56.757] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:56.757] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:69:0) after 1m0s
[19:43:56.823] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:69:0)
[19:43:56.823] Timer tick, asking Buildbucket for the build status
[19:43:57.147] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:57.147] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:70:0) after 1m0s
[19:44:57.260] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:70:0)
[19:44:57.260] Timer tick, asking Buildbucket for the build status
[19:44:57.442] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:57.442] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:71:0) after 1m0s
[19:45:57.524] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:71:0)
[19:45:57.524] Timer tick, asking Buildbucket for the build status
[19:45:57.598] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:57.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:72:0) after 1m0s
[19:46:57.611] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:72:0)
[19:46:57.611] Timer tick, asking Buildbucket for the build status
[19:46:57.786] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:57.786] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:73:0) after 1m0s
[19:47:57.808] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:73:0)
[19:47:57.808] Timer tick, asking Buildbucket for the build status
[19:47:58.327] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:58.327] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:74:0) after 1m0s
[19:48:58.377] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:74:0)
[19:48:58.377] Timer tick, asking Buildbucket for the build status
[19:48:58.828] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:58.828] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:75:0) after 1m0s
[19:49:58.940] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:75:0)
[19:49:58.940] Timer tick, asking Buildbucket for the build status
[19:49:59.030] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:59.030] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:76:0) after 1m0s
[19:50:59.105] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:76:0)
[19:50:59.105] Timer tick, asking Buildbucket for the build status
[19:50:59.183] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:59.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:77:0) after 1m0s
[19:51:59.266] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:77:0)
[19:51:59.266] Timer tick, asking Buildbucket for the build status
[19:51:59.722] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:59.722] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:78:0) after 1m0s
[19:52:59.792] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:78:0)
[19:52:59.792] Timer tick, asking Buildbucket for the build status
[19:52:59.876] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:59.876] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:79:0) after 1m0s
[19:53:59.969] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:79:0)
[19:53:59.969] Timer tick, asking Buildbucket for the build status
[19:54:00.104] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:00.104] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:80:0) after 1m0s
[19:55:00.124] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:80:0)
[19:55:00.124] Timer tick, asking Buildbucket for the build status
[19:55:00.205] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:00.205] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:81:0) after 1m0s
[19:56:00.498] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:81:0)
[19:56:00.498] Timer tick, asking Buildbucket for the build status
[19:56:00.608] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:00.608] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:82:0) after 1m0s
[19:57:00.951] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:82:0)
[19:57:00.951] Timer tick, asking Buildbucket for the build status
[19:57:01.057] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:01.057] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:83:0) after 1m0s
[19:58:01.322] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:83:0)
[19:58:01.322] Timer tick, asking Buildbucket for the build status
[19:58:01.997] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:01.997] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:84:0) after 1m0s
[19:59:02.126] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:84:0)
[19:59:02.126] Timer tick, asking Buildbucket for the build status
[19:59:02.282] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:02.282] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:85:0) after 1m0s
[20:00:02.403] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:85:0)
[20:00:02.403] Timer tick, asking Buildbucket for the build status
[20:00:02.516] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:02.516] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:86:0) after 1m0s
[20:01:02.501] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:86:0)
[20:01:02.501] Timer tick, asking Buildbucket for the build status
[20:01:02.666] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:02.666] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:87:0) after 1m0s
[20:02:02.656] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:87:0)
[20:02:02.656] Timer tick, asking Buildbucket for the build status
[20:02:02.995] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:02.995] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:88:0) after 1m0s
[20:03:03.022] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:88:0)
[20:03:03.022] Timer tick, asking Buildbucket for the build status
[20:03:03.184] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:03.184] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:89:0) after 1m0s
[20:04:03.208] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:89:0)
[20:04:03.208] Timer tick, asking Buildbucket for the build status
[20:04:03.340] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:03.340] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:90:0) after 1m0s
[20:05:03.341] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:90:0)
[20:05:03.341] Timer tick, asking Buildbucket for the build status
[20:05:03.495] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:03.495] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:91:0) after 1m0s
[20:06:03.518] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:91:0)
[20:06:03.518] Timer tick, asking Buildbucket for the build status
[20:06:03.948] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:03.948] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:92:0) after 1m0s
[20:07:03.950] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:92:0)
[20:07:03.950] Timer tick, asking Buildbucket for the build status
[20:07:04.249] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:04.249] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:93:0) after 1m0s
[20:08:04.329] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:93:0)
[20:08:04.329] Timer tick, asking Buildbucket for the build status
[20:08:04.417] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:04.417] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:94:0) after 1m0s
[20:09:04.437] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:94:0)
[20:09:04.437] Timer tick, asking Buildbucket for the build status
[20:09:04.545] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:04.545] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:95:0) after 1m0s
[20:10:04.574] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:95:0)
[20:10:04.574] Timer tick, asking Buildbucket for the build status
[20:10:05.017] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:05.017] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:96:0) after 1m0s
[20:11:05.050] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:96:0)
[20:11:05.050] Timer tick, asking Buildbucket for the build status
[20:11:05.296] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:05.296] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:97:0) after 1m0s
[20:12:05.408] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:97:0)
[20:12:05.408] Timer tick, asking Buildbucket for the build status
[20:12:05.566] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:05.566] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:98:0) after 1m0s
[20:13:05.724] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:98:0)
[20:13:05.724] Timer tick, asking Buildbucket for the build status
[20:13:05.819] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:05.819] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:99:0) after 1m0s
[20:14:05.993] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:99:0)
[20:14:05.993] Timer tick, asking Buildbucket for the build status
[20:14:06.366] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:06.366] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:100:0) after 1m0s
[20:15:06.591] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:100:0)
[20:15:06.591] Timer tick, asking Buildbucket for the build status
[20:15:06.862] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:06.862] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:101:0) after 1m0s
[20:16:07.010] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:101:0)
[20:16:07.010] Timer tick, asking Buildbucket for the build status
[20:16:07.159] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:07.159] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:102:0) after 1m0s
[20:17:07.558] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:102:0)
[20:17:07.558] Timer tick, asking Buildbucket for the build status
[20:17:07.862] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:07.862] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:103:0) after 1m0s
[20:18:07.891] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:103:0)
[20:18:07.891] Timer tick, asking Buildbucket for the build status
[20:18:07.990] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:07.990] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:104:0) after 1m0s
[20:19:08.017] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:104:0)
[20:19:08.017] Timer tick, asking Buildbucket for the build status
[20:19:08.103] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:08.103] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:105:0) after 1m0s
[20:20:08.196] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:105:0)
[20:20:08.196] Timer tick, asking Buildbucket for the build status
[20:20:08.450] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:08.450] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:106:0) after 1m0s
[20:21:08.461] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:106:0)
[20:21:08.461] Timer tick, asking Buildbucket for the build status
[20:21:08.554] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:08.554] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:107:0) after 1m0s
[20:22:08.812] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:107:0)
[20:22:08.813] Timer tick, asking Buildbucket for the build status
[20:22:09.100] Build 8854481534251739872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:09.100] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019911596338789552:108:0) after 1m0s
[20:22:34.047] Received PubSub notification, asking Buildbucket for the build status
[20:22:34.160] Build 8854481534251739872: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[20:22:34.160] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614111752507114",
"created_by": "project:chromium",
"created_ts": "1614105397898379",
"id": "8854481534251739872",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9764750545a7b1c7b982c3bb8037dbde22e111d7\"}}",
"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\": \"9764750545a7b1c7b982c3bb8037dbde22e111d7\", \"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-3-xpf4\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17495, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-2\", \"got_angle_revision\": \"f19f319a59c83d9ccdce08a3b41024cfc997fd8d\", \"got_dawn_revision\": \"76a94b17be3c6cfc7e88d30b90e99d7b89c8f30b\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"9764750545a7b1c7b982c3bb8037dbde22e111d7\", \"got_revision_cp\": \"refs/heads/master@{#856768}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"22186f076bf3bbe1a818792bde93b297e3773f7e\", \"got_v8_revision\": \"8bc6184274d97b9b57d5389e423f6f3f7c092918\", \"got_v8_revision_cp\": \"refs/heads/9.0.239@{#1}\", \"got_webrtc_revision\": \"d0844a80deaab28d7df8e5dff9f71e6ab6919025\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33323}\", \"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\": \"9764750545a7b1c7b982c3bb8037dbde22e111d7\", \"swarm_hashes_refs/heads/master(at){#856768}_without_patch\": {\"absl_hardening_tests\": \"c09b678be0ed806b6f0edbcdb13730c9d2b429e2\", \"accessibility_unittests\": \"876b5a9a6d8404a54a4c55c25aabbe4b8e962f67\", \"angle_unittests\": \"9a33f2e51748be2af06bd7a07675f97ad4b9644a\", \"app_shell_unittests\": \"48c5ef502226bbe9c38ea63e9c9d52d4a107808d\", \"aura_unittests\": \"9a697d8129eaf8c9b3c9e2b28440ddc2196776a4\", \"base_unittests\": \"602e17284bcae693ee29ec8e4087a2b8b0ab6cb0\", \"base_util_unittests\": \"a941540828cdc9294a8f290e1136da7297fe21f9\", \"blink_common_unittests\": \"a243bff360a5f3f347f43a4b9bbe7d966fd0ecd3\", \"blink_fuzzer_unittests\": \"e114a8e19968ce7a4e629210989ed5560c773043\", \"blink_heap_unittests\": \"774517cc8fa79f3257bb9b9954c9d955e647e59b\", \"blink_platform_unittests\": \"9790bea3691265b0a25968bcfa7ec17653aa93c9\", \"blink_unittests\": \"d4e0fe850d51e77529e78acdbb3a78be2b167ed1\", \"boringssl_crypto_tests\": \"eaffa5d12adf692bdb7e8fa939898fe908aba020\", \"boringssl_ssl_tests\": \"b90a5dcfcf288c06f1c7b510298b340cccdb39bf\", \"browser_switcher_bho_unittests\": \"502055f7ba822262a52281d357953ba709b5880c\", \"browser_tests\": \"eb01781084a13c841d657d5ce9008cddbb422eb0\", \"capture_unittests\": \"0581264c453aff8b8bd67fccfe56cb79bc45fafe\", \"cast_unittests\": \"d7e2cd493a042634cdca29ce678d03147c038388\", \"cc_unittests\": \"5a82ddede2386ac421fa5c9b63d7183507662879\", \"chrome_app_unittests\": \"ccfe0dad5a891a213209457cab76cf1393d59e40\", \"chrome_cleaner_unittests\": \"3d1a369c7a2ba4f6f00a3b578324415bf19d2724\", \"chrome_elf_unittests\": \"36352b85fbd97d324c07ac9c94c515dbb329ec63\", \"chromedriver_unittests\": \"e8e08fa846342bfe9697ffbd76693a3fb0085392\", \"components_browsertests\": \"f05c613e0ca9756c2935f99e3092727309ee04aa\", \"components_unittests\": \"c9a19f8f48a61d5b2eef22a1badc25c76fb0c7f8\", \"compositor_unittests\": \"13b377b57edd50854b4bbd24b52cac646282343c\", \"content_browsertests\": \"bac8e67c34ad3dc19fcbbe26223b40539ffbfebd\", \"content_unittests\": \"46c9b4f58badc27d706a588b58f0a2d33138dffb\", \"courgette_unittests\": \"5e5f411c1842bcda1df7c4881aeac1fe9c2d6db5\", \"crashpad_tests\": \"02299e5d3d0577ee61f53495d06d069e4d20744e\", \"cronet_tests\": \"4831f4a7f55cde8e3680b3f325d328367995353a\", \"cronet_unittests\": \"7ec3a94896c2e4848deb93cce73586f72d0ba4e0\", \"crypto_unittests\": \"77790055e53a525dc18193822ac154df6e0e7705\", \"delayloads_unittests\": \"22a1118eea03801d477f66c8faf5614056b9ae8f\", \"device_unittests\": \"9d10021ffdd62846cacaab878bd6513ce4a8ab24\", \"display_unittests\": \"9d7eebc1438039ff408c4b37774a1c123501e9ae\", \"elevation_service_unittests\": \"c27cf5e862f85dc34e1e0f050015da92c8c075a4\", \"events_unittests\": \"f64c47fd6f6b90b7ab15eaa31730a5cb4d55e1b4\", \"extensions_browsertests\": \"03197e5a81a8cf33511007141efb1248a199f2b6\", \"extensions_unittests\": \"6b3abdd0ef3e16a2c38486b33c8c53b1ef37c461\", \"filesystem_service_unittests\": \"20f491b912ef899c049bd23207fba6e0bc4c21b0\", \"gcm_unit_tests\": \"484ce5dbfd90b70358f0d8685c3e49d823d5fc1a\", \"gcp_unittests\": \"9db0137b0d9be0f284190ddd303dd70e5dbfd16c\", \"gfx_unittests\": \"e83e2be4aeec22f1c7e8ee4bedf76ebd825c5e41\", \"gin_unittests\": \"d345b2601993cd4560556c90d7b42c97ee4ba8b0\", \"google_apis_unittests\": \"3ab4d223c544feb82e02c2f2afe17aa24fefb2d3\", \"gpu_unittests\": \"87b4bb63680caa9cedf244089c327e67759c94c3\", \"gwp_asan_unittests\": \"93e7dedd71ad06ca4e9731928c85687ae8303eb8\", \"headless_browsertests\": \"9fd44006fe6e5da8a45388c7c6fa9e805b55cd83\", \"headless_unittests\": \"c080494c27d5b5387e12c2b4490cf3ad12655953\", \"install_static_unittests\": \"85020ea57cbd0ce57d0dd855b51c7daf9719d4a6\", \"installer_util_unittests\": \"9204e202075f1b1b32a76a9e3144f1aa0af684f3\", \"interactive_ui_tests\": \"8fa712ce928996c47a262cc87499ca7f161e0b3d\", \"ipc_tests\": \"8305f256237bbeb0959ce8765701336eb54f4b05\", \"jingle_unittests\": \"9745a87acd1f921af5f0710e1732b47ed53d5cc1\", \"latency_unittests\": \"93e04fb4df952bf2c0cdcc57dd955dc88d93b6e8\", \"libjingle_xmpp_unittests\": \"2848c1ba145d0c68b1be0a2f3b2b9c1d3e775fb0\", \"liburlpattern_unittests\": \"e1d060681d119bdfdecd08e6d7df8f4a11125da2\", \"media_blink_unittests\": \"a7033922eb0c34fa7273e29ad360a2a3b7963642\", \"media_unittests\": \"9054057a31fcd5da5adaf2d48e546543f2d41684\", \"message_center_unittests\": \"d8624225ac0bfa79f93b09fdc19722ae02420d04\", \"midi_unittests\": \"d04f8ba7d6ab9003b099c469305791ef5e9857b9\", \"mojo_core_unittests\": \"d89a4400c4185bf9aad33000eee2b4b2eeb3fe06\", \"mojo_unittests\": \"000ac47c1eb195eee6dae7df776139760b248bd2\", \"nacl_loader_unittests\": \"fe1aedb583c486fbcc3e88d68ef8d83365c38e68\", \"native_theme_unittests\": \"37e79993720d09cb0c1bfebb972f7ed0a1b5c0f7\", \"net_unittests\": \"c378b71cfe4e01ebe4846ac902a10636de9dc04b\", \"notification_helper_unittests\": \"ab2519a47640818aeea315d0e6797a17f9e9ad52\", \"pdf_unittests\": \"eaae7c72397353f726c53d5c6fd7f481e2ad94d7\", \"perfetto_unittests\": \"69e39dd837ab596b3cd4b1694963355a1f38279b\", \"ppapi_unittests\": \"b1570276b178e1b70a39fdfe40f4fb20c811708c\", \"printing_unittests\": \"0ec53dcf72bf7847fd7b158b78c81c551743f9ca\", \"remoting_unittests\": \"1216353b9e84e64bdffbac52c985967a950c9ed9\", \"sbox_integration_tests\": \"7f7d39e2bac7ce89ef60f1e0a4bd9250756285dc\", \"sbox_unittests\": \"cdb819388ceb08f4e441401c98816ca204167a9b\", \"sbox_validation_tests\": \"1c33e4b9e8aad7866111db259bf54c3a86256e77\", \"service_manager_unittests\": \"ffa02e4537b974243058685e75f8edd9a16c83ad\", \"services_unittests\": \"a26fe19631e1507717deca4895084181d69b2563\", \"setup_unittests\": \"3190443a915cccb3f56116d7b01618d720f52963\", \"shell_dialogs_unittests\": \"c836d059540cf4eac3c1bf58102c15fb3d848661\", \"skia_unittests\": \"f74db340837ca70c6d4f2328799e9b59071d94ef\", \"snapshot_unittests\": \"79bf365e82b54170be17d41fe8e6187dbe45dca0\", \"sql_unittests\": \"6dc93b26201fd4dc78f4db2274331a1b2a559899\", \"storage_unittests\": \"78c3d00c4526ddf3f758ed8a2e0386d8955d9543\", \"sync_integration_tests\": \"65e71fa927fd574a05fd7de458df3a7547fca425\", \"traffic_annotation_auditor_unittests\": \"349e9c74ae1c6ec1243b4ff926301acfc05f1985\", \"ui_base_unittests\": \"c6f5cef1c816da941f2952bee30ace7f3651aeac\", \"ui_touch_selection_unittests\": \"f5663cb5a6b89cc1ea6854b4b3a390c8bb4a00a6\", \"unit_tests\": \"bca59fe3e88b83327c4f84b5910d3e2294711148\", \"updater_tests\": \"4e7521df5348e792e8c355774e9ceaa2ac0ec7d8\", \"url_unittests\": \"0a2697a04bc7438c2f43f7e2cd8451029093f106\", \"views_unittests\": \"afa17de046f330119985868ab149c68d6b82254c\", \"viz_unittests\": \"94a50221f4da54e61a183427de798fb077fe07d5\", \"vr_common_unittests\": \"5b22c5c825646b5820eee93ab3aeef5d19195d3f\", \"vr_pixeltests\": \"c91a9bd92cc6a45c95fdaf265e12cdd97a8af615\", \"weblayer_browsertests\": \"52c2b3d9d4c11e05d435c475cd4a0053f0ae58aa\", \"weblayer_unittests\": \"60b15db31d8ceb9cf3567213f619e4a19d87f7b4\", \"wm_unittests\": \"39a7dcccf3879e1d9b3e85f117e67294a7a71c5a\", \"wtf_unittests\": \"de25b9f0a4867e875398183e76b9b0158faf902c\", \"zlib_unittests\": \"a890387f5c9a88dc3667795f0feb5e093dc32e52\", \"zucchini_unittests\": \"bb881c14deea9e67d74c03ecccda6383108c6dfa\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-3-xpf4\"], \"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\": [\"5706-397711c\"], \"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": "1614105546452843",
"status": "COMPLETED",
"status_changed_ts": "1614111753066403",
"tags": [
"build_address:luci.chromium.ci/win-asan/17495",
"builder:win-asan",
"buildset:commit/git/9764750545a7b1c7b982c3bb8037dbde22e111d7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9764750545a7b1c7b982c3bb8037dbde22e111d7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019911596338789552",
"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/8854481534251739872/+/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:51e8ff570e443a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1614111753085528",
"url": "https://ci.chromium.org/b/8854481534251739872",
"utcnow_ts": "1614111754145782"
}
}
[20:22:34.160] Invocation finished in 1h45m57.635819159s with status SUCCEEDED