[08:35:03.163] New invocation is queued and will start shortly
[08:35:04.298] Starting the invocation (attempt 1)
[08:35:04.357] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:35:04.357] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:35:04.357] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9030186900198781120",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"78e996a7cc51af7ef82703674feb96c8251ece95\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9030186900198781120",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/78e996a7cc51af7ef82703674feb96c8251ece95",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/78e996a7cc51af7ef82703674feb96c8251ece95",
"gitiles_ref:refs/heads/master"
]
}
[08:35:05.991] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1604306104556676",
"id": "8864756838067188608",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"78e996a7cc51af7ef82703674feb96c8251ece95\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1604306105736268",
"tags": [
"build_address:luci.chromium.ci/win-asan/15410",
"builder:win-asan",
"buildset:commit/git/78e996a7cc51af7ef82703674feb96c8251ece95",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/78e996a7cc51af7ef82703674feb96c8251ece95",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030186900198781120",
"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/8864756838067188608/+/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": "1604306105736676",
"url": "https://ci.chromium.org/b/8864756838067188608",
"utcnow_ts": "1604306105975079"
}
}
[08:35:05.991] Task URL: https://ci.chromium.org/b/8864756838067188608
[08:35:05.991] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:2:0) after 1m0s
[08:36:06.532] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:2:0)
[08:36:06.532] Timer tick, asking Buildbucket for the build status
[08:36:06.700] Build 8864756838067188608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:36:06.700] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:3:0) after 1m0s
[08:37:06.863] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:3:0)
[08:37:06.863] Timer tick, asking Buildbucket for the build status
[08:37:07.102] Build 8864756838067188608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:37:07.102] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:4:0) after 1m0s
[08:38:07.278] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:4:0)
[08:38:07.278] Timer tick, asking Buildbucket for the build status
[08:38:07.412] Build 8864756838067188608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:38:07.412] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:5:0) after 1m0s
[08:39:07.412] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:5:0)
[08:39:07.412] Timer tick, asking Buildbucket for the build status
[08:39:07.635] Build 8864756838067188608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:39:07.635] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:6:0) after 1m0s
[08:40:07.664] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:6:0)
[08:40:07.664] Timer tick, asking Buildbucket for the build status
[08:40:08.109] Build 8864756838067188608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:40:08.109] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:7:0) after 1m0s
[08:40:17.355] Received PubSub notification, asking Buildbucket for the build status
[08:40:17.455] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:08.147] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:7:0)
[08:41:08.147] Timer tick, asking Buildbucket for the build status
[08:41:08.252] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:08.252] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:9:0) after 1m0s
[08:42:08.364] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:9:0)
[08:42:08.364] Timer tick, asking Buildbucket for the build status
[08:42:08.455] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:08.455] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:10:0) after 1m0s
[08:43:08.516] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:10:0)
[08:43:08.516] Timer tick, asking Buildbucket for the build status
[08:43:08.612] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:08.612] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:11:0) after 1m0s
[08:44:08.663] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:11:0)
[08:44:08.663] Timer tick, asking Buildbucket for the build status
[08:44:08.762] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:08.762] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:12:0) after 1m0s
[08:45:08.805] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:12:0)
[08:45:08.805] Timer tick, asking Buildbucket for the build status
[08:45:08.935] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:08.935] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:13:0) after 1m0s
[08:46:08.988] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:13:0)
[08:46:08.989] Timer tick, asking Buildbucket for the build status
[08:46:09.134] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:09.134] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:14:0) after 1m0s
[08:47:09.189] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:14:0)
[08:47:09.189] Timer tick, asking Buildbucket for the build status
[08:47:09.310] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:09.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:15:0) after 1m0s
[08:48:09.377] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:15:0)
[08:48:09.377] Timer tick, asking Buildbucket for the build status
[08:48:09.474] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:09.474] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:16:0) after 1m0s
[08:49:09.500] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:16:0)
[08:49:09.500] Timer tick, asking Buildbucket for the build status
[08:49:09.585] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:09.585] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:17:0) after 1m0s
[08:50:09.645] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:17:0)
[08:50:09.645] Timer tick, asking Buildbucket for the build status
[08:50:09.812] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:09.812] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:18:0) after 1m0s
[08:51:09.833] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:18:0)
[08:51:09.833] Timer tick, asking Buildbucket for the build status
[08:51:09.956] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:09.956] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:19:0) after 1m0s
[08:52:10.014] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:19:0)
[08:52:10.014] Timer tick, asking Buildbucket for the build status
[08:52:10.094] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:10.094] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:20:0) after 1m0s
[08:53:10.103] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:20:0)
[08:53:10.103] Timer tick, asking Buildbucket for the build status
[08:53:10.178] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:10.178] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:21:0) after 1m0s
[08:54:10.372] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:21:0)
[08:54:10.372] Timer tick, asking Buildbucket for the build status
[08:54:10.456] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:10.456] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:22:0) after 1m0s
[08:55:10.471] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:22:0)
[08:55:10.471] Timer tick, asking Buildbucket for the build status
[08:55:10.655] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:10.655] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:23:0) after 1m0s
[08:56:10.789] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:23:0)
[08:56:10.789] Timer tick, asking Buildbucket for the build status
[08:56:10.875] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:10.875] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:24:0) after 1m0s
[08:57:10.981] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:24:0)
[08:57:10.981] Timer tick, asking Buildbucket for the build status
[08:57:11.113] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:11.113] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:25:0) after 1m0s
[08:58:11.143] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:25:0)
[08:58:11.143] Timer tick, asking Buildbucket for the build status
[08:58:11.425] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:11.425] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:26:0) after 1m0s
[08:59:11.483] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:26:0)
[08:59:11.483] Timer tick, asking Buildbucket for the build status
[08:59:11.606] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:11.606] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:27:0) after 1m0s
[09:00:11.638] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:27:0)
[09:00:11.638] Timer tick, asking Buildbucket for the build status
[09:00:12.048] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:12.049] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:28:0) after 1m0s
[09:01:12.137] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:28:0)
[09:01:12.137] Timer tick, asking Buildbucket for the build status
[09:01:12.252] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:12.252] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:29:0) after 1m0s
[09:02:12.240] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:29:0)
[09:02:12.240] Timer tick, asking Buildbucket for the build status
[09:02:12.329] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:12.329] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:30:0) after 1m0s
[09:03:12.356] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:30:0)
[09:03:12.356] Timer tick, asking Buildbucket for the build status
[09:03:12.485] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:12.485] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:31:0) after 1m0s
[09:04:12.512] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:31:0)
[09:04:12.512] Timer tick, asking Buildbucket for the build status
[09:04:12.619] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:12.619] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:32:0) after 1m0s
[09:05:12.642] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:32:0)
[09:05:12.642] Timer tick, asking Buildbucket for the build status
[09:05:12.778] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:12.778] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:33:0) after 1m0s
[09:06:12.855] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:33:0)
[09:06:12.855] Timer tick, asking Buildbucket for the build status
[09:06:12.939] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:12.939] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:34:0) after 1m0s
[09:07:12.967] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:34:0)
[09:07:12.967] Timer tick, asking Buildbucket for the build status
[09:07:13.033] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:13.033] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:35:0) after 1m0s
[09:08:13.242] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:35:0)
[09:08:13.242] Timer tick, asking Buildbucket for the build status
[09:08:13.396] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:13.396] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:36:0) after 1m0s
[09:09:13.465] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:36:0)
[09:09:13.465] Timer tick, asking Buildbucket for the build status
[09:09:13.611] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:13.611] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:37:0) after 1m0s
[09:10:13.960] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:37:0)
[09:10:13.960] Timer tick, asking Buildbucket for the build status
[09:10:14.118] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:14.118] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:38:0) after 1m0s
[09:11:14.134] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:38:0)
[09:11:14.134] Timer tick, asking Buildbucket for the build status
[09:11:14.218] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:14.218] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:39:0) after 1m0s
[09:12:14.266] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:39:0)
[09:12:14.266] Timer tick, asking Buildbucket for the build status
[09:12:14.385] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:14.385] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:40:0) after 1m0s
[09:13:14.393] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:40:0)
[09:13:14.393] Timer tick, asking Buildbucket for the build status
[09:13:14.449] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:14.450] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:41:0) after 1m0s
[09:14:14.529] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:41:0)
[09:14:14.529] Timer tick, asking Buildbucket for the build status
[09:14:14.604] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:14.604] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:42:0) after 1m0s
[09:15:14.665] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:42:0)
[09:15:14.665] Timer tick, asking Buildbucket for the build status
[09:15:14.789] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:14.789] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:43:0) after 1m0s
[09:16:14.990] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:43:0)
[09:16:14.990] Timer tick, asking Buildbucket for the build status
[09:16:15.066] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:15.066] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:44:0) after 1m0s
[09:17:15.111] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:44:0)
[09:17:15.111] Timer tick, asking Buildbucket for the build status
[09:17:15.255] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:15.255] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:45:0) after 1m0s
[09:18:15.388] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:45:0)
[09:18:15.388] Timer tick, asking Buildbucket for the build status
[09:18:15.595] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:15.595] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:46:0) after 1m0s
[09:19:15.683] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:46:0)
[09:19:15.683] Timer tick, asking Buildbucket for the build status
[09:19:15.795] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:15.795] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:47:0) after 1m0s
[09:20:15.823] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:47:0)
[09:20:15.823] Timer tick, asking Buildbucket for the build status
[09:20:15.977] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:15.977] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:48:0) after 1m0s
[09:21:15.998] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:48:0)
[09:21:15.998] Timer tick, asking Buildbucket for the build status
[09:21:16.094] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:16.094] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:49:0) after 1m0s
[09:22:16.222] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:49:0)
[09:22:16.222] Timer tick, asking Buildbucket for the build status
[09:22:16.289] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:16.289] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:50:0) after 1m0s
[09:23:16.327] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:50:0)
[09:23:16.327] Timer tick, asking Buildbucket for the build status
[09:23:16.442] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:16.442] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:51:0) after 1m0s
[09:24:16.499] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:51:0)
[09:24:16.499] Timer tick, asking Buildbucket for the build status
[09:24:16.556] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:16.556] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:52:0) after 1m0s
[09:25:16.565] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:52:0)
[09:25:16.565] Timer tick, asking Buildbucket for the build status
[09:25:16.676] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:16.676] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:53:0) after 1m0s
[09:26:16.670] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:53:0)
[09:26:16.670] Timer tick, asking Buildbucket for the build status
[09:26:16.730] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:16.731] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:54:0) after 1m0s
[09:27:16.797] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:54:0)
[09:27:16.797] Timer tick, asking Buildbucket for the build status
[09:27:16.998] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:16.998] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:55:0) after 1m0s
[09:28:17.088] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:55:0)
[09:28:17.088] Timer tick, asking Buildbucket for the build status
[09:28:17.169] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:17.169] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:56:0) after 1m0s
[09:29:17.239] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:56:0)
[09:29:17.239] Timer tick, asking Buildbucket for the build status
[09:29:17.305] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:17.305] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:57:0) after 1m0s
[09:30:17.411] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:57:0)
[09:30:17.411] Timer tick, asking Buildbucket for the build status
[09:30:17.966] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:17.966] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:58:0) after 1m0s
[09:31:18.150] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:58:0)
[09:31:18.150] Timer tick, asking Buildbucket for the build status
[09:31:18.214] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:18.214] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:59:0) after 1m0s
[09:32:18.286] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:59:0)
[09:32:18.286] Timer tick, asking Buildbucket for the build status
[09:32:18.369] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:18.369] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:60:0) after 1m0s
[09:33:18.389] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:60:0)
[09:33:18.389] Timer tick, asking Buildbucket for the build status
[09:33:18.503] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:18.503] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:61:0) after 1m0s
[09:34:18.571] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:61:0)
[09:34:18.571] Timer tick, asking Buildbucket for the build status
[09:34:18.674] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:18.674] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:62:0) after 1m0s
[09:35:18.700] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:62:0)
[09:35:18.700] Timer tick, asking Buildbucket for the build status
[09:35:18.861] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:18.861] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:63:0) after 1m0s
[09:36:18.958] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:63:0)
[09:36:18.958] Timer tick, asking Buildbucket for the build status
[09:36:19.085] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:19.085] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:64:0) after 1m0s
[09:37:19.126] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:64:0)
[09:37:19.126] Timer tick, asking Buildbucket for the build status
[09:37:19.224] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:19.224] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:65:0) after 1m0s
[09:38:19.286] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:65:0)
[09:38:19.286] Timer tick, asking Buildbucket for the build status
[09:38:19.443] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:19.443] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:66:0) after 1m0s
[09:39:19.431] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:66:0)
[09:39:19.431] Timer tick, asking Buildbucket for the build status
[09:39:19.508] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:19.508] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:67:0) after 1m0s
[09:40:19.595] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:67:0)
[09:40:19.595] Timer tick, asking Buildbucket for the build status
[09:40:19.858] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:19.858] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:68:0) after 1m0s
[09:41:19.896] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:68:0)
[09:41:19.896] Timer tick, asking Buildbucket for the build status
[09:41:20.106] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:20.106] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:69:0) after 1m0s
[09:42:20.125] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:69:0)
[09:42:20.126] Timer tick, asking Buildbucket for the build status
[09:42:20.246] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:20.247] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:70:0) after 1m0s
[09:43:20.275] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:70:0)
[09:43:20.275] Timer tick, asking Buildbucket for the build status
[09:43:20.415] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:20.415] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:71:0) after 1m0s
[09:44:20.420] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:71:0)
[09:44:20.420] Timer tick, asking Buildbucket for the build status
[09:44:20.596] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:20.596] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:72:0) after 1m0s
[09:45:20.679] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:72:0)
[09:45:20.679] Timer tick, asking Buildbucket for the build status
[09:45:22.117] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:22.117] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:73:0) after 1m0s
[09:46:22.149] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:73:0)
[09:46:22.149] Timer tick, asking Buildbucket for the build status
[09:46:22.268] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:22.268] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:74:0) after 1m0s
[09:47:22.350] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:74:0)
[09:47:22.350] Timer tick, asking Buildbucket for the build status
[09:47:22.501] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:22.501] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:75:0) after 1m0s
[09:48:22.525] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:75:0)
[09:48:22.525] Timer tick, asking Buildbucket for the build status
[09:48:22.657] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:22.657] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:76:0) after 1m0s
[09:49:22.678] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:76:0)
[09:49:22.678] Timer tick, asking Buildbucket for the build status
[09:49:22.780] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:22.780] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:77:0) after 1m0s
[09:50:22.848] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:77:0)
[09:50:22.848] Timer tick, asking Buildbucket for the build status
[09:50:22.978] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:22.978] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:78:0) after 1m0s
[09:51:23.019] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:78:0)
[09:51:23.019] Timer tick, asking Buildbucket for the build status
[09:51:23.171] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:23.171] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:79:0) after 1m0s
[09:52:23.212] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:79:0)
[09:52:23.212] Timer tick, asking Buildbucket for the build status
[09:52:23.347] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:23.347] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:80:0) after 1m0s
[09:53:23.425] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:80:0)
[09:53:23.425] Timer tick, asking Buildbucket for the build status
[09:53:23.933] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:23.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:81:0) after 1m0s
[09:54:23.972] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:81:0)
[09:54:23.972] Timer tick, asking Buildbucket for the build status
[09:54:24.085] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:24.085] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:82:0) after 1m0s
[09:55:24.115] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:82:0)
[09:55:24.115] Timer tick, asking Buildbucket for the build status
[09:55:24.248] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:24.248] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:83:0) after 1m0s
[09:56:24.273] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:83:0)
[09:56:24.274] Timer tick, asking Buildbucket for the build status
[09:56:24.952] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:24.952] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:84:0) after 1m0s
[09:57:24.973] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:84:0)
[09:57:24.973] Timer tick, asking Buildbucket for the build status
[09:57:25.118] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:25.118] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:85:0) after 1m0s
[09:58:25.156] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:85:0)
[09:58:25.156] Timer tick, asking Buildbucket for the build status
[09:58:25.378] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:25.378] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:86:0) after 1m0s
[09:59:26.093] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:86:0)
[09:59:26.093] Timer tick, asking Buildbucket for the build status
[09:59:26.259] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:26.259] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:87:0) after 1m0s
[10:00:27.715] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:87:0)
[10:00:27.715] Timer tick, asking Buildbucket for the build status
[10:00:28.872] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:28.872] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:88:0) after 1m0s
[10:01:32.641] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:88:0)
[10:01:32.641] Timer tick, asking Buildbucket for the build status
[10:01:32.780] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:32.780] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:89:0) after 1m0s
[10:02:33.810] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:89:0)
[10:02:33.810] Timer tick, asking Buildbucket for the build status
[10:02:33.925] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:33.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:90:0) after 1m0s
[10:03:34.883] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:90:0)
[10:03:34.883] Timer tick, asking Buildbucket for the build status
[10:03:34.973] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:34.973] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:91:0) after 1m0s
[10:04:35.575] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:91:0)
[10:04:35.575] Timer tick, asking Buildbucket for the build status
[10:04:35.761] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:35.761] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:92:0) after 1m0s
[10:05:35.969] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:92:0)
[10:05:35.969] Timer tick, asking Buildbucket for the build status
[10:05:36.469] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:36.469] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:93:0) after 1m0s
[10:06:36.557] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:93:0)
[10:06:36.557] Timer tick, asking Buildbucket for the build status
[10:06:36.812] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:36.812] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:94:0) after 1m0s
[10:07:36.943] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:94:0)
[10:07:36.943] Timer tick, asking Buildbucket for the build status
[10:07:38.332] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:38.332] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:95:0) after 1m0s
[10:08:38.378] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:95:0)
[10:08:38.378] Timer tick, asking Buildbucket for the build status
[10:08:38.735] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:38.736] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:96:0) after 1m0s
[10:09:38.841] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:96:0)
[10:09:38.841] Timer tick, asking Buildbucket for the build status
[10:09:38.994] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:38.994] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:97:0) after 1m0s
[10:10:39.063] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:97:0)
[10:10:39.064] Timer tick, asking Buildbucket for the build status
[10:10:39.711] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:39.711] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:98:0) after 1m0s
[10:11:39.712] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:98:0)
[10:11:39.712] Timer tick, asking Buildbucket for the build status
[10:11:39.865] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:39.865] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:99:0) after 1m0s
[10:12:39.946] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:99:0)
[10:12:39.946] Timer tick, asking Buildbucket for the build status
[10:12:40.545] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:40.545] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:100:0) after 1m0s
[10:13:40.601] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:100:0)
[10:13:40.601] Timer tick, asking Buildbucket for the build status
[10:13:41.087] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:41.087] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:101:0) after 1m0s
[10:14:41.289] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:101:0)
[10:14:41.289] Timer tick, asking Buildbucket for the build status
[10:14:41.397] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:41.397] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:102:0) after 1m0s
[10:15:41.456] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:102:0)
[10:15:41.456] Timer tick, asking Buildbucket for the build status
[10:15:41.649] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:41.649] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:103:0) after 1m0s
[10:16:41.689] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:103:0)
[10:16:41.689] Timer tick, asking Buildbucket for the build status
[10:16:42.261] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:42.261] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:104:0) after 1m0s
[10:17:42.363] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:104:0)
[10:17:42.363] Timer tick, asking Buildbucket for the build status
[10:17:43.430] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:43.430] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:105:0) after 1m0s
[10:18:43.486] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:105:0)
[10:18:43.486] Timer tick, asking Buildbucket for the build status
[10:18:43.634] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:43.634] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:106:0) after 1m0s
[10:19:43.640] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:106:0)
[10:19:43.640] Timer tick, asking Buildbucket for the build status
[10:19:43.739] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:43.739] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:107:0) after 1m0s
[10:20:43.803] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:107:0)
[10:20:43.803] Timer tick, asking Buildbucket for the build status
[10:20:43.957] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:43.957] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:108:0) after 1m0s
[10:21:43.969] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:108:0)
[10:21:43.969] Timer tick, asking Buildbucket for the build status
[10:21:44.093] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:44.093] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:109:0) after 1m0s
[10:22:44.185] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:109:0)
[10:22:44.185] Timer tick, asking Buildbucket for the build status
[10:22:44.263] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:44.263] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:110:0) after 1m0s
[10:23:44.478] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:110:0)
[10:23:44.478] Timer tick, asking Buildbucket for the build status
[10:23:44.552] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:44.552] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:111:0) after 1m0s
[10:24:45.035] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:111:0)
[10:24:45.035] Timer tick, asking Buildbucket for the build status
[10:24:45.111] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:45.111] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:112:0) after 1m0s
[10:25:45.346] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:112:0)
[10:25:45.346] Timer tick, asking Buildbucket for the build status
[10:25:45.459] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:45.459] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:113:0) after 1m0s
[10:26:45.558] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:113:0)
[10:26:45.558] Timer tick, asking Buildbucket for the build status
[10:26:45.708] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:45.708] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:114:0) after 1m0s
[10:27:45.845] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:114:0)
[10:27:45.845] Timer tick, asking Buildbucket for the build status
[10:27:45.975] Build 8864756838067188608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:45.975] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030186900198781120:115:0) after 1m0s
[10:28:08.568] Received PubSub notification, asking Buildbucket for the build status
[10:28:08.655] Build 8864756838067188608: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[10:28:08.655] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1604312887816091",
"created_by": "project:chromium",
"created_ts": "1604306104556676",
"id": "8864756838067188608",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"78e996a7cc51af7ef82703674feb96c8251ece95\"}}",
"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\": \"78e996a7cc51af7ef82703674feb96c8251ece95\", \"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-5-vrjs\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15410, \"clang_revision\": \"llvmorg-12-init-5627-gf086e85e-2\", \"got_angle_revision\": \"068bb086e1c844161c18185a9110f9588f5b6e8a\", \"got_dawn_revision\": \"0a374dce665328391ab3b68fd9e667b018dd9dcd\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"78e996a7cc51af7ef82703674feb96c8251ece95\", \"got_revision_cp\": \"refs/heads/master@{#823078}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"e0de282e678b72dac25b81f8d25305231aec01f8\", \"got_v8_revision\": \"be169f638f5edd2af0946618b0c98c9339f33627\", \"got_v8_revision_cp\": \"refs/heads/8.8.215@{#1}\", \"got_webrtc_revision\": \"ee24eb66d23ffea5ef729dc340e1fc4381c72a4a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32507}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"78e996a7cc51af7ef82703674feb96c8251ece95\", \"swarm_hashes_refs/heads/master(at){#823078}_without_patch\": {\"absl_hardening_tests\": \"d5a86944f2a750fb455a9a2699620e68bdd97190\", \"accessibility_unittests\": \"c741b4a86d6063f36195f02c1c4d6b00f6f659e7\", \"angle_unittests\": \"93d666cacde050d6d11937c7c9a60a4141874ae2\", \"app_shell_unittests\": \"c3ab58167433d9052100e919989e09c1d5181016\", \"aura_unittests\": \"a9b020739f488c54a94fca16e76e9f1cc7d60c7a\", \"base_unittests\": \"dff8dc06b5c39f36d1f7122678558135b60c9fdf\", \"base_util_unittests\": \"5ebd7ae8e2bf642902d1e8348fb5b7c271f5c80e\", \"blink_common_unittests\": \"d8a7f7928ea049de115c73b91a72b53647dc8c9c\", \"blink_fuzzer_unittests\": \"2eff3a6990d1817e6881bc9f6a86c54c74d18678\", \"blink_heap_unittests\": \"9ab802da2e82d36f44dc998d24544e7dd4f98920\", \"blink_platform_unittests\": \"265905aee0a69f212e4255fa31ca68a96ec43d1b\", \"blink_unittests\": \"c47940a3af7fc34005f3b265baf411cd6fdb5c6b\", \"boringssl_crypto_tests\": \"88cbfece12d9cb6fc9b8ac4db1e012d8775d31a2\", \"boringssl_ssl_tests\": \"bd1f7c2582a548a6144caa5ea57061db5d3159d5\", \"browser_switcher_bho_unittests\": \"1ed50446122794a2fa7b2c38f63f8972cffd5b8c\", \"browser_tests\": \"84b2d2ad759b6b91be6b17dde35036c818a76567\", \"capture_unittests\": \"93951d9dae603875b01a6c5610890c712b2b59ee\", \"cast_unittests\": \"61509b2c2cab64a7cc633320c05fb062e0c7e3fc\", \"cc_unittests\": \"3dc6fef2fbf7f9be94273b28cddfd8fe93de35cd\", \"chrome_app_unittests\": \"365dd3e4bdf6080a57fdf4528306399e784cd3f7\", \"chrome_cleaner_unittests\": \"8e4a21aa2a37845363fc3481aae3f36a376ef1dc\", \"chrome_elf_unittests\": \"b0819026c46f492e2e39012851305e29f44c7621\", \"chromedriver_unittests\": \"5e406681968afca0f287f941086efd1f88dec66c\", \"components_browsertests\": \"d8582b5b087b52bd81e2bee6c33ea334fb6a99b2\", \"components_unittests\": \"734cb404902e1849c196cb29f096094c6b3405f4\", \"compositor_unittests\": \"45dbb481317cdb74dc3ac39d88505ad7cc16f577\", \"content_browsertests\": \"d221193d3b24b31683aa3c967895c65a6d4f65e9\", \"content_unittests\": \"c3c40dfc16dc14ecfbb83dc865096bfa6f36e341\", \"courgette_unittests\": \"7d13ee8c04571136cc677c437e6d5ca9b3b07f6f\", \"crashpad_tests\": \"7d50ff645dde37d0f12477bec3b0ebc2ff9c6556\", \"cronet_tests\": \"a787394c649a2c6d233c305dc868ae673d1ac9ab\", \"cronet_unittests\": \"0d6c2e06e990000f25a5cf122b8cd2ada4dd022f\", \"crypto_unittests\": \"6719b0c5b489d31b48daea1b5cdbee159cda404f\", \"delayloads_unittests\": \"e38f015fd8549aeaa8120af0ac245d45e0c6baa0\", \"device_unittests\": \"bf9a50a3e8b9d1cb797f3e872ff2a33c984e9ff9\", \"display_unittests\": \"1263d498c57add70fef07d87ed22f3f608862a32\", \"elevation_service_unittests\": \"8a90fb55d888e4b31468bc635a7b43285f0964e4\", \"events_unittests\": \"229f23129275ca157cb38b4744bcfa912826e771\", \"extensions_browsertests\": \"a81937ae1df78b54390e224afce102eb08833f49\", \"extensions_unittests\": \"6786f885552d75e4bc578974b72a9949d9ac08e2\", \"filesystem_service_unittests\": \"4b2e8b3e4bd32caa7ef2ae35b06f476acb47be08\", \"gcm_unit_tests\": \"dcf8eac2f4512eb1eec8cc12fe1a4ec838e5d849\", \"gcp_unittests\": \"66c0157886eac0b8d341f6af8dced13444e3c3ae\", \"gfx_unittests\": \"a003b9ad9d507a925ae7daff4f1bcbc7aee34488\", \"gin_unittests\": \"b834e060882477e64f6f931b504a3abc31613d29\", \"google_apis_unittests\": \"90f88c098d935c05c26193dca08ef64639bcd2c8\", \"gpu_unittests\": \"5547356bd7d7fb45ab08d33bf6c28c821a19842a\", \"gwp_asan_unittests\": \"38a832f8d8d57c9f0f126591410bd172beca474d\", \"headless_browsertests\": \"dd81abb56562a2bff0684a3988c46dad011c4764\", \"headless_unittests\": \"ce56349e9ec3888259ba88e139a80fddde551dd1\", \"install_static_unittests\": \"0113705afce518735ebc94df605d91d1618dd705\", \"installer_util_unittests\": \"13de28ff0c1bd2e17bd9844d9b4cb3807d83a799\", \"interactive_ui_tests\": \"25dcc92505ab5481d187e6945152e8e1f16cb579\", \"ipc_tests\": \"4e88cf786df54c9c3a2effee60aa4029b34156b2\", \"jingle_unittests\": \"57c714b8670b394f1e1c58dbb846eea169089371\", \"latency_unittests\": \"ef1f17bcd8ebd0c5924c7e88ea86edcbbca89371\", \"libjingle_xmpp_unittests\": \"1b08636bc77c9f7b7932280b93f38f72c7560990\", \"liburlpattern_unittests\": \"9be2c250e7759e35a7fb76719aee63f52d108561\", \"media_blink_unittests\": \"fb7e247381a3877e435441f048367020d8c2b481\", \"media_unittests\": \"9edbdaf1586f7e716e4b32fc290dcca9e9b1882a\", \"message_center_unittests\": \"a9888af75e23a478e263e844740dd935c00edfff\", \"midi_unittests\": \"8113edd74d8b717c4f44d5e2f0a8860321330ee3\", \"mojo_core_unittests\": \"e48c911080a6f5f560be8c1afea807a97a9275b0\", \"mojo_unittests\": \"4468424a7e809a957ee4b21119233df6eec92ab0\", \"nacl_loader_unittests\": \"122cb2757987d109f5fb477601a46f38892c4961\", \"native_theme_unittests\": \"3abffb4231d94a055a8941d85a073007130728a0\", \"net_unittests\": \"f25a0ab68735471605bc807c379e10ade0cf2b3b\", \"notification_helper_unittests\": \"a88392e03be3198920aef89e84a9c7fb708ca2d9\", \"pdf_unittests\": \"3347ccc36e7174fc9b5929d302900192a8f58103\", \"perfetto_unittests\": \"c95d5f0a9c3aa8a18b77d8ad5835e9a6b6f25c35\", \"ppapi_unittests\": \"9e085e19dd98c8e5bafb835eee0a2c21a3084bde\", \"printing_unittests\": \"eee576a591e606ab4289b395bd4acc1d45bed7bb\", \"remoting_unittests\": \"2f8afd12de50ba8ee2a85fbd1d56298bdac10a86\", \"sbox_integration_tests\": \"dbceea529608fd7babb570c35b15b1cbfb279d3c\", \"sbox_unittests\": \"b80b87758729672cffa9968eeb2c811496263cea\", \"sbox_validation_tests\": \"6769eb943f975f2910eab84bf63d18e826b46922\", \"service_manager_unittests\": \"58688b79351c7ce18a31a24001e943058bb3024c\", \"services_unittests\": \"58b4cf2925e8f382017fbae5e1efca856d4c47c2\", \"setup_unittests\": \"ee98d912049b14e4c10faeeed83c8e5ac002e3d6\", \"shell_dialogs_unittests\": \"4852fe8d3ec9a624a96886fa5315af26de3c6f97\", \"skia_unittests\": \"d9e0b36aeb1a6d5f1866db2fd0fa7a74e78343b0\", \"snapshot_unittests\": \"aede42d7886e4a68d22a7b87b1df12830a0892ca\", \"sql_unittests\": \"37c52cd19638482955dd59cf4266d5d6cdb033e9\", \"storage_unittests\": \"0146e511d251c857d764c7c1e40174b153d60f62\", \"sync_integration_tests\": \"d607df858d9db53562ba9662ec6a24ef43d41199\", \"traffic_annotation_auditor_unittests\": \"50c79a81338d28720caf80b8586a632eca986d69\", \"ui_base_unittests\": \"11ab6921e839b6af12081e3ad6b4d29bad322f2b\", \"ui_touch_selection_unittests\": \"7558366ce1e6092bedc6b4dda978e3e67b910d7f\", \"unit_tests\": \"16c3c1d22f28cb46dd8dc1d9fb681da119bba26c\", \"updater_tests\": \"a805e28f7b10c6c2dee948536b21ed012097a263\", \"url_unittests\": \"aadab304eace47ad3864218fef55f8fe0ab21943\", \"views_unittests\": \"d2af875231b513476704fa792b85134b6beb6983\", \"viz_unittests\": \"338fe73e3ad94f5559b5c74e7d7ace5735aca8f0\", \"vr_common_unittests\": \"8400282647af4568937498fff02e7a3ad03e65f1\", \"vr_pixeltests\": \"0131b6bfad4eacd4134dc145c758849eec8c80df\", \"weblayer_browsertests\": \"208973c91908d175baa3e0a3480bc4381eff8cb4\", \"weblayer_unittests\": \"c6b9d4c822631abbfcaa999a9cdb6ae52c47099e\", \"wm_unittests\": \"36d10797925183f3db1580c50677e42d283fcfc4\", \"wtf_unittests\": \"2438f96cc00457af545c22566ef630d96d03fa54\", \"zlib_unittests\": \"68dcde7ea2dc3f534e393188e9561c32ecd644c8\", \"zucchini_unittests\": \"944d862ac9b593887a7d63a508f6d5ea9e14c8e6\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_4f77261552e8d7e3833e5abc9c27108a2b925a0ef53cb3b8532fd8ec4484401e_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-5-vrjs\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5614-d862eaa\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1604306416019944",
"status": "COMPLETED",
"status_changed_ts": "1604312888097478",
"tags": [
"build_address:luci.chromium.ci/win-asan/15410",
"builder:win-asan",
"buildset:commit/git/78e996a7cc51af7ef82703674feb96c8251ece95",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/78e996a7cc51af7ef82703674feb96c8251ece95",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030186900198781120",
"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/8864756838067188608/+/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:4fa0ea011fe0f710",
"user_agent:luci-scheduler"
],
"updated_ts": "1604312888104452",
"url": "https://ci.chromium.org/b/8864756838067188608",
"utcnow_ts": "1604312888644019"
}
}
[10:28:08.655] Invocation finished in 1h53m5.515255665s with status SUCCEEDED