[21:53:46.574] New invocation is queued and will start shortly
[21:53:47.965] Starting the invocation (attempt 1)
[21:53:48.008] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:53:48.008] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:53:48.008] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022345309762894848",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"894364e3da5fb63d1cb158e7671c9e63891f10b7\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9022345309762894848",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/894364e3da5fb63d1cb158e7671c9e63891f10b7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/894364e3da5fb63d1cb158e7671c9e63891f10b7",
"gitiles_ref:refs/heads/master"
]
}
[21:53:48.708] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611784428123846",
"id": "8856915247454323664",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"894364e3da5fb63d1cb158e7671c9e63891f10b7\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611784428527829",
"tags": [
"build_address:luci.chromium.ci/win-asan/17040",
"builder:win-asan",
"buildset:commit/git/894364e3da5fb63d1cb158e7671c9e63891f10b7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/894364e3da5fb63d1cb158e7671c9e63891f10b7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022345309762894848",
"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/8856915247454323664/+/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": "1611784428528040",
"url": "https://ci.chromium.org/b/8856915247454323664",
"utcnow_ts": "1611784428697325"
}
}
[21:53:48.708] Task URL: https://ci.chromium.org/b/8856915247454323664
[21:53:48.708] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:2:0) after 1m0s
[21:54:48.812] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:2:0)
[21:54:48.812] Timer tick, asking Buildbucket for the build status
[21:54:48.915] Build 8856915247454323664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:54:48.915] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:3:0) after 1m0s
[21:55:48.938] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:3:0)
[21:55:48.938] Timer tick, asking Buildbucket for the build status
[21:55:49.058] Build 8856915247454323664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:55:49.058] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:4:0) after 1m0s
[21:56:49.055] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:4:0)
[21:56:49.055] Timer tick, asking Buildbucket for the build status
[21:56:49.190] Build 8856915247454323664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:56:49.190] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:5:0) after 1m0s
[21:57:49.253] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:5:0)
[21:57:49.253] Timer tick, asking Buildbucket for the build status
[21:57:49.409] Build 8856915247454323664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:57:49.409] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:6:0) after 1m0s
[21:58:49.515] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:6:0)
[21:58:49.515] Timer tick, asking Buildbucket for the build status
[21:58:49.595] Build 8856915247454323664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:58:49.595] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:7:0) after 1m0s
[21:59:20.895] Received PubSub notification, asking Buildbucket for the build status
[21:59:21.294] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:49.622] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:7:0)
[21:59:49.622] Timer tick, asking Buildbucket for the build status
[21:59:50.122] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:50.122] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:9:0) after 1m0s
[22:00:50.134] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:9:0)
[22:00:50.134] Timer tick, asking Buildbucket for the build status
[22:00:50.558] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:50.558] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:10:0) after 1m0s
[22:01:50.653] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:10:0)
[22:01:50.653] Timer tick, asking Buildbucket for the build status
[22:01:50.822] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:50.822] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:11:0) after 1m0s
[22:02:50.895] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:11:0)
[22:02:50.895] Timer tick, asking Buildbucket for the build status
[22:02:51.292] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:51.292] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:12:0) after 1m0s
[22:03:51.325] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:12:0)
[22:03:51.325] Timer tick, asking Buildbucket for the build status
[22:03:52.299] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:52.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:13:0) after 1m0s
[22:04:52.292] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:13:0)
[22:04:52.292] Timer tick, asking Buildbucket for the build status
[22:04:52.358] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:52.358] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:14:0) after 1m0s
[22:05:52.403] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:14:0)
[22:05:52.403] Timer tick, asking Buildbucket for the build status
[22:05:52.471] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:52.471] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:15:0) after 1m0s
[22:06:52.478] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:15:0)
[22:06:52.478] Timer tick, asking Buildbucket for the build status
[22:06:52.581] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:52.581] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:16:0) after 1m0s
[22:07:52.653] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:16:0)
[22:07:52.653] Timer tick, asking Buildbucket for the build status
[22:07:52.737] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:52.737] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:17:0) after 1m0s
[22:08:52.777] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:17:0)
[22:08:52.777] Timer tick, asking Buildbucket for the build status
[22:08:52.875] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:52.875] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:18:0) after 1m0s
[22:09:52.985] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:18:0)
[22:09:52.985] Timer tick, asking Buildbucket for the build status
[22:09:54.451] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:54.451] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:19:0) after 1m0s
[22:10:54.658] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:19:0)
[22:10:54.658] Timer tick, asking Buildbucket for the build status
[22:10:55.043] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:55.044] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:20:0) after 1m0s
[22:11:55.232] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:20:0)
[22:11:55.232] Timer tick, asking Buildbucket for the build status
[22:11:55.286] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:55.286] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:21:0) after 1m0s
[22:12:55.395] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:21:0)
[22:12:55.395] Timer tick, asking Buildbucket for the build status
[22:12:55.575] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:55.575] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:22:0) after 1m0s
[22:13:55.655] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:22:0)
[22:13:55.656] Timer tick, asking Buildbucket for the build status
[22:13:55.825] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:55.825] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:23:0) after 1m0s
[22:14:55.844] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:23:0)
[22:14:55.844] Timer tick, asking Buildbucket for the build status
[22:14:55.899] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:55.899] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:24:0) after 1m0s
[22:15:55.936] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:24:0)
[22:15:55.936] Timer tick, asking Buildbucket for the build status
[22:15:56.073] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:56.073] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:25:0) after 1m0s
[22:16:56.107] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:25:0)
[22:16:56.108] Timer tick, asking Buildbucket for the build status
[22:16:56.328] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:56.328] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:26:0) after 1m0s
[22:17:56.343] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:26:0)
[22:17:56.343] Timer tick, asking Buildbucket for the build status
[22:17:56.440] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:56.440] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:27:0) after 1m0s
[22:18:56.513] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:27:0)
[22:18:56.513] Timer tick, asking Buildbucket for the build status
[22:18:56.947] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:56.947] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:28:0) after 1m0s
[22:19:56.975] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:28:0)
[22:19:56.975] Timer tick, asking Buildbucket for the build status
[22:19:57.325] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:57.325] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:29:0) after 1m0s
[22:20:57.401] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:29:0)
[22:20:57.401] Timer tick, asking Buildbucket for the build status
[22:20:57.561] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:57.561] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:30:0) after 1m0s
[22:21:57.635] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:30:0)
[22:21:57.635] Timer tick, asking Buildbucket for the build status
[22:21:57.945] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:57.945] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:31:0) after 1m0s
[22:22:57.958] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:31:0)
[22:22:57.958] Timer tick, asking Buildbucket for the build status
[22:22:58.113] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:58.113] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:32:0) after 1m0s
[22:23:58.115] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:32:0)
[22:23:58.115] Timer tick, asking Buildbucket for the build status
[22:23:58.659] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:58.659] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:33:0) after 1m0s
[22:24:58.687] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:33:0)
[22:24:58.687] Timer tick, asking Buildbucket for the build status
[22:24:58.917] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:58.917] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:34:0) after 1m0s
[22:25:58.963] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:34:0)
[22:25:58.963] Timer tick, asking Buildbucket for the build status
[22:25:59.109] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:59.109] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:35:0) after 1m0s
[22:26:59.132] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:35:0)
[22:26:59.132] Timer tick, asking Buildbucket for the build status
[22:26:59.240] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:59.241] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:36:0) after 1m0s
[22:27:59.271] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:36:0)
[22:27:59.271] Timer tick, asking Buildbucket for the build status
[22:27:59.470] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:59.470] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:37:0) after 1m0s
[22:28:59.496] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:37:0)
[22:28:59.496] Timer tick, asking Buildbucket for the build status
[22:28:59.728] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:59.728] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:38:0) after 1m0s
[22:29:59.734] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:38:0)
[22:29:59.734] Timer tick, asking Buildbucket for the build status
[22:29:59.864] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:59.864] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:39:0) after 1m0s
[22:30:59.881] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:39:0)
[22:30:59.881] Timer tick, asking Buildbucket for the build status
[22:31:00.223] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:00.223] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:40:0) after 1m0s
[22:32:00.265] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:40:0)
[22:32:00.265] Timer tick, asking Buildbucket for the build status
[22:32:00.340] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:00.340] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:41:0) after 1m0s
[22:33:00.446] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:41:0)
[22:33:00.446] Timer tick, asking Buildbucket for the build status
[22:33:00.586] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:00.586] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:42:0) after 1m0s
[22:34:01.952] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:42:0)
[22:34:01.952] Timer tick, asking Buildbucket for the build status
[22:34:02.858] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:02.858] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:43:0) after 1m0s
[22:35:03.573] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:43:0)
[22:35:03.573] Timer tick, asking Buildbucket for the build status
[22:35:03.660] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:03.660] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:44:0) after 1m0s
[22:36:03.723] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:44:0)
[22:36:03.723] Timer tick, asking Buildbucket for the build status
[22:36:03.857] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:03.857] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:45:0) after 1m0s
[22:37:03.855] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:45:0)
[22:37:03.855] Timer tick, asking Buildbucket for the build status
[22:37:05.083] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:05.083] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:46:0) after 1m0s
[22:38:05.457] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:46:0)
[22:38:05.457] Timer tick, asking Buildbucket for the build status
[22:38:05.525] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:05.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:47:0) after 1m0s
[22:39:05.683] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:47:0)
[22:39:05.683] Timer tick, asking Buildbucket for the build status
[22:39:06.337] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:06.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:48:0) after 1m0s
[22:40:06.378] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:48:0)
[22:40:06.378] Timer tick, asking Buildbucket for the build status
[22:40:06.438] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:06.438] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:49:0) after 1m0s
[22:41:06.540] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:49:0)
[22:41:06.541] Timer tick, asking Buildbucket for the build status
[22:41:06.969] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:06.969] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:50:0) after 1m0s
[22:42:07.057] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:50:0)
[22:42:07.057] Timer tick, asking Buildbucket for the build status
[22:42:07.226] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:07.226] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:51:0) after 1m0s
[22:43:07.271] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:51:0)
[22:43:07.271] Timer tick, asking Buildbucket for the build status
[22:43:07.343] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:07.344] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:52:0) after 1m0s
[22:44:07.407] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:52:0)
[22:44:07.407] Timer tick, asking Buildbucket for the build status
[22:44:08.607] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:08.607] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:53:0) after 1m0s
[22:45:08.647] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:53:0)
[22:45:08.647] Timer tick, asking Buildbucket for the build status
[22:45:08.819] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:08.819] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:54:0) after 1m0s
[22:46:08.839] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:54:0)
[22:46:08.839] Timer tick, asking Buildbucket for the build status
[22:46:08.916] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:08.916] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:55:0) after 1m0s
[22:47:08.950] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:55:0)
[22:47:08.950] Timer tick, asking Buildbucket for the build status
[22:47:09.922] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:09.922] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:56:0) after 1m0s
[22:48:09.979] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:56:0)
[22:48:09.979] Timer tick, asking Buildbucket for the build status
[22:48:10.058] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:10.058] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:57:0) after 1m0s
[22:49:10.133] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:57:0)
[22:49:10.133] Timer tick, asking Buildbucket for the build status
[22:49:10.262] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:10.262] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:58:0) after 1m0s
[22:50:10.461] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:58:0)
[22:50:10.461] Timer tick, asking Buildbucket for the build status
[22:50:10.630] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:10.630] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:59:0) after 1m0s
[22:51:10.719] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:59:0)
[22:51:10.719] Timer tick, asking Buildbucket for the build status
[22:51:10.916] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:10.916] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:60:0) after 1m0s
[22:52:10.941] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:60:0)
[22:52:10.941] Timer tick, asking Buildbucket for the build status
[22:52:11.122] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:11.122] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:61:0) after 1m0s
[22:53:11.147] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:61:0)
[22:53:11.147] Timer tick, asking Buildbucket for the build status
[22:53:11.545] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:11.545] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:62:0) after 1m0s
[22:54:11.641] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:62:0)
[22:54:11.641] Timer tick, asking Buildbucket for the build status
[22:54:11.798] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:11.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:63:0) after 1m0s
[22:55:11.818] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:63:0)
[22:55:11.818] Timer tick, asking Buildbucket for the build status
[22:55:11.943] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:11.943] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:64:0) after 1m0s
[22:56:11.998] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:64:0)
[22:56:11.998] Timer tick, asking Buildbucket for the build status
[22:56:12.150] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:12.150] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:65:0) after 1m0s
[22:57:12.220] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:65:0)
[22:57:12.220] Timer tick, asking Buildbucket for the build status
[22:57:12.459] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:12.459] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:66:0) after 1m0s
[22:58:12.435] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:66:0)
[22:58:12.435] Timer tick, asking Buildbucket for the build status
[22:58:12.526] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:12.526] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:67:0) after 1m0s
[22:59:12.670] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:67:0)
[22:59:12.670] Timer tick, asking Buildbucket for the build status
[22:59:13.333] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:13.333] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:68:0) after 1m0s
[23:00:13.765] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:68:0)
[23:00:13.765] Timer tick, asking Buildbucket for the build status
[23:00:13.857] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:13.858] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:69:0) after 1m0s
[23:01:13.953] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:69:0)
[23:01:13.953] Timer tick, asking Buildbucket for the build status
[23:01:14.275] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:14.275] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:70:0) after 1m0s
[23:02:14.285] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:70:0)
[23:02:14.285] Timer tick, asking Buildbucket for the build status
[23:02:15.768] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:15.768] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:71:0) after 1m0s
[23:03:15.788] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:71:0)
[23:03:15.788] Timer tick, asking Buildbucket for the build status
[23:03:16.224] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:16.224] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:72:0) after 1m0s
[23:04:16.292] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:72:0)
[23:04:16.292] Timer tick, asking Buildbucket for the build status
[23:04:16.399] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:16.399] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:73:0) after 1m0s
[23:05:16.493] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:73:0)
[23:05:16.493] Timer tick, asking Buildbucket for the build status
[23:05:16.618] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:16.618] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:74:0) after 1m0s
[23:06:16.697] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:74:0)
[23:06:16.697] Timer tick, asking Buildbucket for the build status
[23:06:17.541] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:17.541] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:75:0) after 1m0s
[23:07:17.551] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:75:0)
[23:07:17.551] Timer tick, asking Buildbucket for the build status
[23:07:17.714] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:17.714] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:76:0) after 1m0s
[23:08:17.759] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:76:0)
[23:08:17.759] Timer tick, asking Buildbucket for the build status
[23:08:17.917] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:17.917] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:77:0) after 1m0s
[23:09:17.978] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:77:0)
[23:09:17.978] Timer tick, asking Buildbucket for the build status
[23:09:18.179] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:18.179] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:78:0) after 1m0s
[23:10:18.203] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:78:0)
[23:10:18.203] Timer tick, asking Buildbucket for the build status
[23:10:18.520] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:18.520] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:79:0) after 1m0s
[23:11:18.592] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:79:0)
[23:11:18.592] Timer tick, asking Buildbucket for the build status
[23:11:18.815] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:18.815] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:80:0) after 1m0s
[23:12:18.888] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:80:0)
[23:12:18.888] Timer tick, asking Buildbucket for the build status
[23:12:19.161] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:19.161] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:81:0) after 1m0s
[23:13:19.266] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:81:0)
[23:13:19.266] Timer tick, asking Buildbucket for the build status
[23:13:19.804] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:19.804] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:82:0) after 1m0s
[23:14:19.851] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:82:0)
[23:14:19.851] Timer tick, asking Buildbucket for the build status
[23:14:20.124] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:20.124] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:83:0) after 1m0s
[23:15:20.178] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:83:0)
[23:15:20.178] Timer tick, asking Buildbucket for the build status
[23:15:20.349] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:20.349] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:84:0) after 1m0s
[23:16:20.388] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:84:0)
[23:16:20.388] Timer tick, asking Buildbucket for the build status
[23:16:20.502] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:20.502] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:85:0) after 1m0s
[23:17:20.699] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:85:0)
[23:17:20.699] Timer tick, asking Buildbucket for the build status
[23:17:21.165] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:21.165] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:86:0) after 1m0s
[23:18:21.380] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:86:0)
[23:18:21.380] Timer tick, asking Buildbucket for the build status
[23:18:21.543] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:21.543] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:87:0) after 1m0s
[23:19:21.783] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:87:0)
[23:19:21.783] Timer tick, asking Buildbucket for the build status
[23:19:22.038] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:22.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:88:0) after 1m0s
[23:20:22.133] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:88:0)
[23:20:22.133] Timer tick, asking Buildbucket for the build status
[23:20:22.304] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:22.305] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:89:0) after 1m0s
[23:21:22.480] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:89:0)
[23:21:22.480] Timer tick, asking Buildbucket for the build status
[23:21:22.823] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:22.823] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:90:0) after 1m0s
[23:22:22.943] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:90:0)
[23:22:22.943] Timer tick, asking Buildbucket for the build status
[23:22:23.817] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:23.817] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:91:0) after 1m0s
[23:23:24.271] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:91:0)
[23:23:24.271] Timer tick, asking Buildbucket for the build status
[23:23:24.376] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:24.376] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:92:0) after 1m0s
[23:24:24.756] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:92:0)
[23:24:24.756] Timer tick, asking Buildbucket for the build status
[23:24:25.013] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:25.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:93:0) after 1m0s
[23:25:25.139] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:93:0)
[23:25:25.139] Timer tick, asking Buildbucket for the build status
[23:25:25.233] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:25.233] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:94:0) after 1m0s
[23:26:25.352] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:94:0)
[23:26:25.352] Timer tick, asking Buildbucket for the build status
[23:26:25.466] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:25.466] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:95:0) after 1m0s
[23:27:25.538] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:95:0)
[23:27:25.538] Timer tick, asking Buildbucket for the build status
[23:27:26.365] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:26.365] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:96:0) after 1m0s
[23:28:26.587] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:96:0)
[23:28:26.587] Timer tick, asking Buildbucket for the build status
[23:28:26.848] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:26.848] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:97:0) after 1m0s
[23:29:26.950] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:97:0)
[23:29:26.950] Timer tick, asking Buildbucket for the build status
[23:29:27.188] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:27.188] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:98:0) after 1m0s
[23:30:27.166] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:98:0)
[23:30:27.166] Timer tick, asking Buildbucket for the build status
[23:30:28.357] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:28.357] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:99:0) after 1m0s
[23:31:28.409] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:99:0)
[23:31:28.409] Timer tick, asking Buildbucket for the build status
[23:31:28.936] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:28.936] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:100:0) after 1m0s
[23:32:29.044] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:100:0)
[23:32:29.044] Timer tick, asking Buildbucket for the build status
[23:32:29.154] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:29.154] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:101:0) after 1m0s
[23:33:29.234] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:101:0)
[23:33:29.234] Timer tick, asking Buildbucket for the build status
[23:33:29.623] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:29.623] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:102:0) after 1m0s
[23:34:29.691] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:102:0)
[23:34:29.691] Timer tick, asking Buildbucket for the build status
[23:34:29.982] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:29.982] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:103:0) after 1m0s
[23:35:30.054] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:103:0)
[23:35:30.054] Timer tick, asking Buildbucket for the build status
[23:35:30.566] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:30.566] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:104:0) after 1m0s
[23:36:30.774] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:104:0)
[23:36:30.774] Timer tick, asking Buildbucket for the build status
[23:36:31.054] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:31.054] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:105:0) after 1m0s
[23:37:31.089] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:105:0)
[23:37:31.089] Timer tick, asking Buildbucket for the build status
[23:37:31.610] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:31.610] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:106:0) after 1m0s
[23:38:31.721] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:106:0)
[23:38:31.721] Timer tick, asking Buildbucket for the build status
[23:38:31.870] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:31.870] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:107:0) after 1m0s
[23:39:32.017] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:107:0)
[23:39:32.017] Timer tick, asking Buildbucket for the build status
[23:39:32.323] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:32.323] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:108:0) after 1m0s
[23:40:32.481] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:108:0)
[23:40:32.481] Timer tick, asking Buildbucket for the build status
[23:40:32.581] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:32.581] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:109:0) after 1m0s
[23:41:32.695] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:109:0)
[23:41:32.695] Timer tick, asking Buildbucket for the build status
[23:41:33.269] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:33.269] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:110:0) after 1m0s
[23:42:33.319] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:110:0)
[23:42:33.319] Timer tick, asking Buildbucket for the build status
[23:42:33.510] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:33.510] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:111:0) after 1m0s
[23:43:33.543] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:111:0)
[23:43:33.543] Timer tick, asking Buildbucket for the build status
[23:43:33.775] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:33.775] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:112:0) after 1m0s
[23:44:33.811] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:112:0)
[23:44:33.811] Timer tick, asking Buildbucket for the build status
[23:44:33.962] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:33.962] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:113:0) after 1m0s
[23:45:33.988] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:113:0)
[23:45:33.989] Timer tick, asking Buildbucket for the build status
[23:45:34.307] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:34.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:114:0) after 1m0s
[23:46:34.392] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:114:0)
[23:46:34.392] Timer tick, asking Buildbucket for the build status
[23:46:34.567] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:34.567] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:115:0) after 1m0s
[23:47:34.687] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:115:0)
[23:47:34.687] Timer tick, asking Buildbucket for the build status
[23:47:34.811] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:34.811] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:116:0) after 1m0s
[23:48:34.827] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:116:0)
[23:48:34.827] Timer tick, asking Buildbucket for the build status
[23:48:34.945] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:34.945] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:117:0) after 1m0s
[23:49:35.012] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:117:0)
[23:49:35.012] Timer tick, asking Buildbucket for the build status
[23:49:35.211] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:35.211] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:118:0) after 1m0s
[23:50:35.258] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:118:0)
[23:50:35.258] Timer tick, asking Buildbucket for the build status
[23:50:35.459] Build 8856915247454323664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:35.459] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022345309762894848:119:0) after 1m0s
[23:50:48.068] Received PubSub notification, asking Buildbucket for the build status
[23:50:48.717] Build 8856915247454323664: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:50:48.717] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611791447035967",
"created_by": "project:chromium",
"created_ts": "1611784428123846",
"id": "8856915247454323664",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"894364e3da5fb63d1cb158e7671c9e63891f10b7\"}}",
"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\": \"894364e3da5fb63d1cb158e7671c9e63891f10b7\", \"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-6-pcsp\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17040, \"clang_revision\": \"llvmorg-12-init-17251-g6de48655-1\", \"got_angle_revision\": \"52afb6f17cbd13a290c2798b565067f47d47af86\", \"got_dawn_revision\": \"c1d3a66bd217395a501543b09c6d2c25e9d2e22e\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"894364e3da5fb63d1cb158e7671c9e63891f10b7\", \"got_revision_cp\": \"refs/heads/master@{#847805}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"3e9b79ff42de0f5547809354de89e24c7a439e39\", \"got_v8_revision\": \"74ef1d1cf560c22c37a43f202a6275c8ed92ae97\", \"got_v8_revision_cp\": \"refs/heads/9.0.85@{#1}\", \"got_webrtc_revision\": \"49dbad021e802197b3c0b966c03b4aa766a5b578\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33080}\", \"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\": \"894364e3da5fb63d1cb158e7671c9e63891f10b7\", \"swarm_hashes_refs/heads/master(at){#847805}_without_patch\": {\"absl_hardening_tests\": \"b00cde2fb12fea9a1973388eb070e08f1ac58941\", \"accessibility_unittests\": \"3b290b3d4b5e64a9e74bb3ba53a55d459c94e4ca\", \"angle_unittests\": \"58161dba4159677389bd963413cecbbb03971238\", \"app_shell_unittests\": \"f7a8abe4c5abfa477a46a193f634ddfc6543b1f6\", \"aura_unittests\": \"cef5ce994f7ebfa2eca6a6ee727a3a268f548451\", \"base_unittests\": \"24d26cbb26f5d8ed4c45d2eced7709b510667b30\", \"base_util_unittests\": \"8f94bf6b08a62468868db8cdcce1c0499e9ad0bf\", \"blink_common_unittests\": \"6c1fc843341a14af06c2e9361dc755f926a5b83a\", \"blink_fuzzer_unittests\": \"eab0b80df80b9dda6d29c0f410cd1b9c018a4b67\", \"blink_heap_unittests\": \"3730769fb659897276cbd47c52c8e888c0ab97f1\", \"blink_platform_unittests\": \"950362292cc2a7f97dc19dd318e6ec436b25d8dc\", \"blink_unittests\": \"fa66b4568c271e4859a53935d0dbbfafbd724a54\", \"boringssl_crypto_tests\": \"675ca56a8ec4683dec8ed51e4bd99f3cd3204351\", \"boringssl_ssl_tests\": \"be3dbf8ac7bf2854de2345b09c64232dfaaf1fb7\", \"browser_switcher_bho_unittests\": \"97727a49e99fed3fc71f738c1d427927ad23be98\", \"browser_tests\": \"7e6593acfa6c2c2b3b2c99d894b44161f9af8f32\", \"capture_unittests\": \"07b47b6510badbd65e00e8008351d40632ec9516\", \"cast_unittests\": \"84344c1813757b23885a41f8e402dac5559c2e39\", \"cc_unittests\": \"aeb64420d236111cc202dc52ae1d323c4b461412\", \"chrome_app_unittests\": \"844fb493be33703b12aa984615ff036db3f9075f\", \"chrome_cleaner_unittests\": \"4b278e8f9107000ddf570d5ee45b4655a1153d2e\", \"chrome_elf_unittests\": \"5489b6ea836ee32cb511df00974670974931af2e\", \"chromedriver_unittests\": \"016b869516fc57ff5d10140c0e879ad06784bb17\", \"components_browsertests\": \"06f55c40a058b492a833c65fb0923dfbe8dbd93e\", \"components_unittests\": \"0f7a18aa036518b8869f84f5881da239a10d5232\", \"compositor_unittests\": \"4e6b4af87691e0304560ca9c4b597fe2b9d3deda\", \"content_browsertests\": \"4a95b198ff174d571a0b8a851a1104f681b94ca9\", \"content_unittests\": \"d3bd3b8f14243d13c09cea40cc8ec6b789cb9b88\", \"courgette_unittests\": \"8033850a7be59890b315f2a2bb56f9e95532d8a1\", \"crashpad_tests\": \"66a58525e7a31b6811dda63405bd1edd3b2d4be1\", \"cronet_tests\": \"7dd93639d0959baf2fb638b664c4e4cfd773f937\", \"cronet_unittests\": \"ee4169f3f89b56a8eb2c85dc861b9ff7ae09d534\", \"crypto_unittests\": \"12f965fcfc9eed97c8af4cbdc18d854d7b322719\", \"delayloads_unittests\": \"9050a4dca77bc90908d1b25b7038fee2331a3207\", \"device_unittests\": \"d46762782498cb739d9d7cad88fcf11e987b0fbf\", \"display_unittests\": \"6959ea8ee588f5bd3d04cf25982395e8ec8aa8d5\", \"elevation_service_unittests\": \"b1d2ac0d7179b111781969d5ec18c110f278e765\", \"events_unittests\": \"0c908130694bc6c1d11f4f6089ab6431b34cd1dd\", \"extensions_browsertests\": \"34619d0a7c2b0e364392d23149730773b70516e0\", \"extensions_unittests\": \"f1853074fcf3ddeb2f6d6af4b01e1cbda56d61be\", \"filesystem_service_unittests\": \"9fbbe2594e9303ef9fa0e11e7145eec3de1e8e63\", \"gcm_unit_tests\": \"0a7f1bdaeb2e65ef7201e44b2d3572531b55df45\", \"gcp_unittests\": \"dfb692d92bed1c5df42b252369c38f03f2047218\", \"gfx_unittests\": \"352e0f8198fe3e66225d17e1dab7565f0959e1a2\", \"gin_unittests\": \"f694c2b63d084d8db0e557bcee2bcd7a6efaf395\", \"google_apis_unittests\": \"49f1b3f0fd6965150a23c837e28262d341b9e8f2\", \"gpu_unittests\": \"9041af975cd8dff2b978193cd95b507eaf1e14eb\", \"gwp_asan_unittests\": \"427993932d7cf9394b790da5fad3e1f08e200a19\", \"headless_browsertests\": \"f2e9df1b8c4c0859f6b2a13216f51f9abb727d81\", \"headless_unittests\": \"6cce283b12862f8c729c5036098289334a8dbf17\", \"install_static_unittests\": \"bae3a100f15bf3aa852b40722c30be93bad55b75\", \"installer_util_unittests\": \"84d13ec2738063525e4433d3ceac367777132cfc\", \"interactive_ui_tests\": \"58e2ae94baa5d9f1b13a7a147b03e976aef244ee\", \"ipc_tests\": \"e8a66b54e7803a8f956979bcf70e4110023ad25f\", \"jingle_unittests\": \"c125396012551b97d886eb1d67c467e2c2a15cda\", \"latency_unittests\": \"ff50b25ff524ef5074568fab9c51d5ee5e7115aa\", \"libjingle_xmpp_unittests\": \"bab5117e3eeee0549e8f12cc7b8e5db0aca6324d\", \"liburlpattern_unittests\": \"b23c1cc4584332f32d316078ba61ffcc565b94f8\", \"media_blink_unittests\": \"6b49bc98ebb83929504c1df91ec86ebe93eb1c56\", \"media_unittests\": \"e33cd640bd46a5549c3c12a75d7e41f4c659ef0d\", \"message_center_unittests\": \"ea435b6b59873305d7977397070dc0445ab5790c\", \"midi_unittests\": \"67a4014a765aebb0e83371af1856e71d908abcf8\", \"mojo_core_unittests\": \"5315ed6e73b65a0d28e7e2c99d6250df660a801e\", \"mojo_unittests\": \"40e6487a9e4aa15689516cf7d47b4c78446318af\", \"nacl_loader_unittests\": \"e64d349c1c422a508c525ae58d78ce6a9a652ff3\", \"native_theme_unittests\": \"9aa49a1fecb74505a695d737fb1dbe301aac928c\", \"net_unittests\": \"c7639c4ea833b463173e6997bdf39ecaf63ad933\", \"notification_helper_unittests\": \"3e32a2b63e0e506899a085b9b4550ad60d8fcedb\", \"pdf_unittests\": \"0b28f28272fce78c8f0bf5b47f89fdd51338d8f9\", \"perfetto_unittests\": \"16a35585a049c744a8fa58cc2054d3b265ca66ef\", \"ppapi_unittests\": \"83caa7417adb89ee830c416677062b3eb45f97d4\", \"printing_unittests\": \"e956d5896509cb89a3708744026acdf07d63efed\", \"remoting_unittests\": \"f20a86826d3f70435232dca4e733321ae2237d83\", \"sbox_integration_tests\": \"ee0d4c96345b510a7e4a7e5f185a66451ee9df8c\", \"sbox_unittests\": \"471fbb0e2ae2cf57a844d0b127fe52946a2487e4\", \"sbox_validation_tests\": \"4d5c6b2c675f08e52aa0051f036a12f3b92932c1\", \"service_manager_unittests\": \"9876e418f081924b1d7bf27e39a1a5b322d26b93\", \"services_unittests\": \"b2a05deb7cafea9b656f6fe536401bd4eec58d90\", \"setup_unittests\": \"b171d4dfa65d30b6e541eba4997142b53e634f25\", \"shell_dialogs_unittests\": \"f4f90de0f7917286c7bcf6dd86b190bbec5133dd\", \"skia_unittests\": \"bd9ee5515be1a56b2093cb4f22a3abea2fbd9fc7\", \"snapshot_unittests\": \"d17fc8a8c2b864edc8264c3c083ee4b649133b72\", \"sql_unittests\": \"99fe53c676934e9e927e41311b22b930b9b7b0d2\", \"storage_unittests\": \"3884c930cd9818493b2fbc38707fd41fb3960dd3\", \"sync_integration_tests\": \"3ea20cec5d8c4cc9308dd17376cb23738057766a\", \"traffic_annotation_auditor_unittests\": \"b6e0837d005d6fa3be7bae133ad3ad4e2c0e772f\", \"ui_base_unittests\": \"61f5709034d8cc3ea2578c1cd495363ec83634d1\", \"ui_touch_selection_unittests\": \"fbe4a5ef24a02c020fbceb4549048993fb5d400e\", \"unit_tests\": \"f20ca614eb05a45be7e591d220a5501f8fb8788a\", \"updater_tests\": \"b942d656e7b7963b5f91343b9f1589ad58609735\", \"url_unittests\": \"fece4834dd558ad953dff2b9600ce9f8a488a4be\", \"views_unittests\": \"5062509cb102f8d8b04cc9e0bd60b2b99cf21e52\", \"viz_unittests\": \"ad9dc11b53ed3f0c59e8c615e8901681cb495cc0\", \"vr_common_unittests\": \"54ba79fbc52f0ca4a598579ef9abbcea056349ed\", \"vr_pixeltests\": \"33cc36e366a7893ca170019d25adefee8fe4799d\", \"weblayer_browsertests\": \"567f65e2491f6dcbce9f269c8a339ba70b49e635\", \"weblayer_unittests\": \"91e6e7f39dd77d99527ad953d06d1add2a098f9e\", \"wm_unittests\": \"d9a946d6cca8cfb83dacbf3c55b5062255d2d1b5\", \"wtf_unittests\": \"ab78360f1b296cd60ccd356ce23ed1a8855eac27\", \"zlib_unittests\": \"b5687bf99f4654b3f9eae5d79fa6300d91239cd9\", \"zucchini_unittests\": \"40b2a0c60f4306457a024ac8020abe854ad59204\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"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-6-pcsp\"], \"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\": [\"5672-4713de1\"], \"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": "1611784760143379",
"status": "COMPLETED",
"status_changed_ts": "1611791447454232",
"tags": [
"build_address:luci.chromium.ci/win-asan/17040",
"builder:win-asan",
"buildset:commit/git/894364e3da5fb63d1cb158e7671c9e63891f10b7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/894364e3da5fb63d1cb158e7671c9e63891f10b7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022345309762894848",
"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/8856915247454323664/+/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:515ea82555155f10",
"user_agent:luci-scheduler"
],
"updated_ts": "1611791447501740",
"url": "https://ci.chromium.org/b/8856915247454323664",
"utcnow_ts": "1611791448705960"
}
}
[23:50:48.717] Invocation finished in 1h57m2.179265265s with status SUCCEEDED