[05:23:56.268] New invocation is queued and will start shortly
[05:23:57.340] Starting the invocation (attempt 1)
[05:23:57.377] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:23:57.377] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:23:57.377] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9025578478817697312",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"ffe46599e8a9e11905335f512e02dbcea9dd2f2e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9025578478817697312",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/ffe46599e8a9e11905335f512e02dbcea9dd2f2e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ffe46599e8a9e11905335f512e02dbcea9dd2f2e",
"gitiles_ref:refs/heads/master"
]
}
[05:23:59.074] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1608701037581333",
"id": "8860148416775300640",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ffe46599e8a9e11905335f512e02dbcea9dd2f2e\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1608701038858726",
"tags": [
"build_address:luci.chromium.ci/win-asan/16400",
"builder:win-asan",
"buildset:commit/git/ffe46599e8a9e11905335f512e02dbcea9dd2f2e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ffe46599e8a9e11905335f512e02dbcea9dd2f2e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025578478817697312",
"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/8860148416775300640/+/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": "1608701038858910",
"url": "https://ci.chromium.org/b/8860148416775300640",
"utcnow_ts": "1608701039056650"
}
}
[05:23:59.074] Task URL: https://ci.chromium.org/b/8860148416775300640
[05:23:59.074] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:2:0) after 1m0s
[05:24:01.414] Received PubSub notification, asking Buildbucket for the build status
[05:24:01.527] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:59.153] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:2:0)
[05:24:59.153] Timer tick, asking Buildbucket for the build status
[05:24:59.234] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:59.234] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:4:0) after 1m0s
[05:25:59.357] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:4:0)
[05:25:59.357] Timer tick, asking Buildbucket for the build status
[05:25:59.659] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:59.659] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:5:0) after 1m0s
[05:26:59.653] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:5:0)
[05:26:59.653] Timer tick, asking Buildbucket for the build status
[05:26:59.800] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:59.800] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:6:0) after 1m0s
[05:27:59.854] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:6:0)
[05:27:59.854] Timer tick, asking Buildbucket for the build status
[05:27:59.952] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:59.952] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:7:0) after 1m0s
[05:28:59.973] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:7:0)
[05:28:59.973] Timer tick, asking Buildbucket for the build status
[05:29:00.174] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:00.174] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:8:0) after 1m0s
[05:30:00.266] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:8:0)
[05:30:00.266] Timer tick, asking Buildbucket for the build status
[05:30:00.339] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:00.339] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:9:0) after 1m0s
[05:31:00.511] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:9:0)
[05:31:00.511] Timer tick, asking Buildbucket for the build status
[05:31:00.585] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:00.585] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:10:0) after 1m0s
[05:32:00.624] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:10:0)
[05:32:00.624] Timer tick, asking Buildbucket for the build status
[05:32:00.817] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:00.817] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:11:0) after 1m0s
[05:33:01.228] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:11:0)
[05:33:01.228] Timer tick, asking Buildbucket for the build status
[05:33:01.318] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:01.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:12:0) after 1m0s
[05:34:01.531] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:12:0)
[05:34:01.531] Timer tick, asking Buildbucket for the build status
[05:34:01.685] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:01.685] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:13:0) after 1m0s
[05:35:01.712] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:13:0)
[05:35:01.712] Timer tick, asking Buildbucket for the build status
[05:35:02.831] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:02.831] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:14:0) after 1m0s
[05:36:02.842] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:14:0)
[05:36:02.842] Timer tick, asking Buildbucket for the build status
[05:36:02.935] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:02.935] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:15:0) after 1m0s
[05:37:02.946] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:15:0)
[05:37:02.946] Timer tick, asking Buildbucket for the build status
[05:37:03.632] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:03.632] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:16:0) after 1m0s
[05:38:03.665] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:16:0)
[05:38:03.665] Timer tick, asking Buildbucket for the build status
[05:38:03.789] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:03.789] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:17:0) after 1m0s
[05:39:03.891] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:17:0)
[05:39:03.891] Timer tick, asking Buildbucket for the build status
[05:39:04.177] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:04.178] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:18:0) after 1m0s
[05:40:04.189] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:18:0)
[05:40:04.189] Timer tick, asking Buildbucket for the build status
[05:40:04.308] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:04.308] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:19:0) after 1m0s
[05:41:04.332] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:19:0)
[05:41:04.332] Timer tick, asking Buildbucket for the build status
[05:41:04.438] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:04.438] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:20:0) after 1m0s
[05:42:04.482] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:20:0)
[05:42:04.482] Timer tick, asking Buildbucket for the build status
[05:42:04.647] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:04.647] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:21:0) after 1m0s
[05:43:04.700] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:21:0)
[05:43:04.700] Timer tick, asking Buildbucket for the build status
[05:43:04.793] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:04.793] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:22:0) after 1m0s
[05:44:04.996] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:22:0)
[05:44:04.996] Timer tick, asking Buildbucket for the build status
[05:44:05.326] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:05.326] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:23:0) after 1m0s
[05:45:05.471] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:23:0)
[05:45:05.471] Timer tick, asking Buildbucket for the build status
[05:45:05.610] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:05.610] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:24:0) after 1m0s
[05:46:05.673] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:24:0)
[05:46:05.673] Timer tick, asking Buildbucket for the build status
[05:46:05.861] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:05.862] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:25:0) after 1m0s
[05:47:05.882] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:25:0)
[05:47:05.882] Timer tick, asking Buildbucket for the build status
[05:47:06.006] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:06.006] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:26:0) after 1m0s
[05:48:06.059] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:26:0)
[05:48:06.059] Timer tick, asking Buildbucket for the build status
[05:48:06.279] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:06.279] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:27:0) after 1m0s
[05:49:06.299] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:27:0)
[05:49:06.299] Timer tick, asking Buildbucket for the build status
[05:49:06.467] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:06.467] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:28:0) after 1m0s
[05:50:06.521] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:28:0)
[05:50:06.522] Timer tick, asking Buildbucket for the build status
[05:50:06.592] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:06.592] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:29:0) after 1m0s
[05:51:06.704] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:29:0)
[05:51:06.704] Timer tick, asking Buildbucket for the build status
[05:51:06.764] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:06.764] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:30:0) after 1m0s
[05:52:06.835] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:30:0)
[05:52:06.835] Timer tick, asking Buildbucket for the build status
[05:52:06.917] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:06.917] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:31:0) after 1m0s
[05:53:06.993] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:31:0)
[05:53:06.993] Timer tick, asking Buildbucket for the build status
[05:53:07.161] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:07.162] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:32:0) after 1m0s
[05:54:07.201] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:32:0)
[05:54:07.201] Timer tick, asking Buildbucket for the build status
[05:54:07.311] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:07.311] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:33:0) after 1m0s
[05:55:07.406] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:33:0)
[05:55:07.406] Timer tick, asking Buildbucket for the build status
[05:55:07.492] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:07.492] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:34:0) after 1m0s
[05:56:07.561] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:34:0)
[05:56:07.561] Timer tick, asking Buildbucket for the build status
[05:56:08.480] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:08.480] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:35:0) after 1m0s
[05:57:08.522] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:35:0)
[05:57:08.522] Timer tick, asking Buildbucket for the build status
[05:57:08.658] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:08.658] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:36:0) after 1m0s
[05:58:08.731] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:36:0)
[05:58:08.731] Timer tick, asking Buildbucket for the build status
[05:58:08.806] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:08.806] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:37:0) after 1m0s
[05:59:08.836] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:37:0)
[05:59:08.836] Timer tick, asking Buildbucket for the build status
[05:59:08.936] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:08.936] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:38:0) after 1m0s
[06:00:08.960] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:38:0)
[06:00:08.960] Timer tick, asking Buildbucket for the build status
[06:00:10.867] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:10.867] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:39:0) after 1m0s
[06:01:13.878] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:39:0)
[06:01:13.878] Timer tick, asking Buildbucket for the build status
[06:01:14.276] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:14.276] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:40:0) after 1m0s
[06:02:14.292] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:40:0)
[06:02:14.292] Timer tick, asking Buildbucket for the build status
[06:02:14.417] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:14.417] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:41:0) after 1m0s
[06:03:14.461] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:41:0)
[06:03:14.461] Timer tick, asking Buildbucket for the build status
[06:03:14.634] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:14.634] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:42:0) after 1m0s
[06:04:14.668] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:42:0)
[06:04:14.668] Timer tick, asking Buildbucket for the build status
[06:04:14.858] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:14.858] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:43:0) after 1m0s
[06:05:14.956] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:43:0)
[06:05:14.956] Timer tick, asking Buildbucket for the build status
[06:05:15.038] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:15.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:44:0) after 1m0s
[06:06:15.025] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:44:0)
[06:06:15.025] Timer tick, asking Buildbucket for the build status
[06:06:15.220] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:15.220] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:45:0) after 1m0s
[06:07:15.276] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:45:0)
[06:07:15.276] Timer tick, asking Buildbucket for the build status
[06:07:15.483] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:15.483] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:46:0) after 1m0s
[06:08:15.523] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:46:0)
[06:08:15.523] Timer tick, asking Buildbucket for the build status
[06:08:15.597] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:15.597] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:47:0) after 1m0s
[06:09:15.726] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:47:0)
[06:09:15.726] Timer tick, asking Buildbucket for the build status
[06:09:15.857] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:15.857] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:48:0) after 1m0s
[06:10:15.988] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:48:0)
[06:10:15.988] Timer tick, asking Buildbucket for the build status
[06:10:16.146] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:16.146] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:49:0) after 1m0s
[06:11:16.214] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:49:0)
[06:11:16.214] Timer tick, asking Buildbucket for the build status
[06:11:16.313] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:16.313] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:50:0) after 1m0s
[06:12:16.363] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:50:0)
[06:12:16.363] Timer tick, asking Buildbucket for the build status
[06:12:16.656] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:16.656] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:51:0) after 1m0s
[06:13:16.677] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:51:0)
[06:13:16.677] Timer tick, asking Buildbucket for the build status
[06:13:16.763] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:16.763] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:52:0) after 1m0s
[06:14:16.785] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:52:0)
[06:14:16.785] Timer tick, asking Buildbucket for the build status
[06:14:16.986] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:16.986] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:53:0) after 1m0s
[06:15:16.972] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:53:0)
[06:15:16.972] Timer tick, asking Buildbucket for the build status
[06:15:17.117] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:17.117] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:54:0) after 1m0s
[06:16:17.136] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:54:0)
[06:16:17.136] Timer tick, asking Buildbucket for the build status
[06:16:17.223] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:17.223] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:55:0) after 1m0s
[06:17:17.267] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:55:0)
[06:17:17.267] Timer tick, asking Buildbucket for the build status
[06:17:17.354] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:17.354] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:56:0) after 1m0s
[06:18:17.347] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:56:0)
[06:18:17.347] Timer tick, asking Buildbucket for the build status
[06:18:17.456] Build 8860148416775300640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:17.456] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025578478817697312:57:0) after 1m0s
[06:18:23.537] Received PubSub notification, asking Buildbucket for the build status
[06:18:23.607] Build 8860148416775300640: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[06:18:23.607] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1608704302516150",
"created_by": "project:chromium",
"created_ts": "1608701037581333",
"id": "8860148416775300640",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ffe46599e8a9e11905335f512e02dbcea9dd2f2e\"}}",
"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\": \"ffe46599e8a9e11905335f512e02dbcea9dd2f2e\", \"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-gpyc\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16400, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"5c0ba5437be05c887189a566e4f99220b68ec8ca\", \"got_dawn_revision\": \"33bf309c1d45e4da80ba77913eab9dadfc8f5e26\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"ffe46599e8a9e11905335f512e02dbcea9dd2f2e\", \"got_revision_cp\": \"refs/heads/master@{#839058}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"1cc5b3357d2ff3c81ef7ec0b07a6660796bde5cd\", \"got_v8_revision\": \"407251b826bdb2683e639456c81b4a82de640240\", \"got_v8_revision_cp\": \"refs/heads/8.9.185@{#1}\", \"got_webrtc_revision\": \"c463a784c340874c75032da7602ec1fde43f75ed\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32874}\", \"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\": \"ffe46599e8a9e11905335f512e02dbcea9dd2f2e\", \"swarm_hashes_refs/heads/master(at){#839058}_without_patch\": {\"absl_hardening_tests\": \"ce1f0f9b9b25e3cdf6a07a0e0b76af9716afb48f\", \"accessibility_unittests\": \"30fbd9393531324c0bbea130e55df128605896de\", \"angle_unittests\": \"93d2c285464b0022b9c1f3ef183400d1a8710796\", \"app_shell_unittests\": \"58e51e48fe097236271c0a2ce3a4b9d6a8950500\", \"aura_unittests\": \"643e67ba3918e7a9119ce40a13b5d77879e8af0a\", \"base_unittests\": \"7ca7a1bb35aac42e0d7146e65d0f45bccbd8b1d1\", \"base_util_unittests\": \"5c845bf46e78057b517371484642806e1e521fd4\", \"blink_common_unittests\": \"579970202a6c763d199d101032cb7d6951633e4a\", \"blink_fuzzer_unittests\": \"901b90e12c5e92d9b83d24dbcba573788f8678c5\", \"blink_heap_unittests\": \"908205dcdea4981fa5db77c615d038fca22c7ba9\", \"blink_platform_unittests\": \"12bba8e61cd640d834e0c22b252b38034079e831\", \"blink_unittests\": \"9c6ace7a65e47858ae6e0f47020c4a27aee52a66\", \"boringssl_crypto_tests\": \"2504f21ea2ffdfb788e76dcef30b9541551ef265\", \"boringssl_ssl_tests\": \"cedf376879c94d77766e67635c228f52690a5ae8\", \"browser_switcher_bho_unittests\": \"a778b9e8cd53b81c550f894474346318a7bfba9d\", \"browser_tests\": \"7012d0459e92b0104bae0743ef32500623a1410b\", \"capture_unittests\": \"bc09cf9ebd8bea70ad05033748e39d20294bcc4d\", \"cast_unittests\": \"8113c2a90227d9beb633437344e8cbc5208f5788\", \"cc_unittests\": \"d3e862ccd4ec8597095ad5ebc712ad3979fd61c0\", \"chrome_app_unittests\": \"8edf9d5fbacf9fec2a2c86d3b1620c58839d3af6\", \"chrome_cleaner_unittests\": \"ef985271248808e928b9d5050c1cecafc6295542\", \"chrome_elf_unittests\": \"1d78c160d451bc60a97ce163225abdb55f0e5e1b\", \"chromedriver_unittests\": \"687149d855db4bf123e9be1a9ad1fc09860ca9a8\", \"components_browsertests\": \"f403a6098231da96d26f3a1f0c5785a59a46324e\", \"components_unittests\": \"b492a80e9010491173afe8aa833e63f4e5fb78b5\", \"compositor_unittests\": \"ac4d2106a694f8a9a49ded3596053592b9917f32\", \"content_browsertests\": \"f36f6ba4dcc98616c9d88599957cfb4a189648fe\", \"content_unittests\": \"c1fd9d98b310a94632de0e16cc9f7af58c68398d\", \"courgette_unittests\": \"9255ed270c746a4277c076afb8e4ecc0765a665e\", \"crashpad_tests\": \"505482293273a34ff74cf1fe369e89dbddb15977\", \"cronet_tests\": \"7a9b09a8aa0c185fa6297f2c11aa679520451d21\", \"cronet_unittests\": \"32ac780691e63b92d2c3b6e10968b1c1237f25e5\", \"crypto_unittests\": \"c48cfbbd3fe8af209aefad43c9d29265df725895\", \"delayloads_unittests\": \"eddb2b45091dbb9f1f2398af7c81d7afa97a6848\", \"device_unittests\": \"b67ba0d4d9338396665dbb01f89e420f67639a9a\", \"display_unittests\": \"0d5e3123755d93fd59b42b3011a243f5a5b9dbbc\", \"elevation_service_unittests\": \"7dc6a478f5bcdc17f0fd49b5a23cd33d6f8d157c\", \"events_unittests\": \"4743b0c8c7419241602fd5490ebb847fb4a1ff66\", \"extensions_browsertests\": \"0990bf7cc8f1718097b3abe55c41ceaa92d1b57f\", \"extensions_unittests\": \"3ca1c90d4bb22a419cba4481ce00b43865ebc5cb\", \"filesystem_service_unittests\": \"d092594ef413e064bdeff37e62044f5b2a101bd6\", \"gcm_unit_tests\": \"254496cf3db352ff3bc68859a34c5637d34d3a49\", \"gcp_unittests\": \"c96f30e3cd380af2e00bbb3c0fbc22a741639b15\", \"gfx_unittests\": \"e858888990f8ec196b244320534b41269673db8e\", \"gin_unittests\": \"bef1678a830268bf30c7fc9feeb06012eb490d5b\", \"google_apis_unittests\": \"c2fb0a97fb338ae8fb65adc2fe40b6f3ae43b28a\", \"gpu_unittests\": \"0166b483954cfc94459fee6da2862f0a9f5bd318\", \"gwp_asan_unittests\": \"4ee736f8e9eb3049d0288af289c5b9b8a75d030f\", \"headless_browsertests\": \"faa6e311ea39a87ef58993751fcd5d4a8ff7d9b0\", \"headless_unittests\": \"bb1d7b53b87f04b664cdb994140342d98b49e49b\", \"install_static_unittests\": \"280548038f19c95a74f1ed98de8a493fa3a63f01\", \"installer_util_unittests\": \"9b4bd40444de5133b421547a67d1801eb6be9c3e\", \"interactive_ui_tests\": \"cc11366421a4d405070ee409a72743bc71a9bc54\", \"ipc_tests\": \"4a04e979b7bf4609cd781bac2f50263972d457d8\", \"jingle_unittests\": \"0f727a8e66d9bacf7747bb6206f4851c0966a9da\", \"latency_unittests\": \"8151a18aa9bfa476451f7310237886623c992928\", \"libjingle_xmpp_unittests\": \"ccd9bf5de47731a4925b3f48fc0e0084f636b7e0\", \"liburlpattern_unittests\": \"e83204cc574022d43bc66ff187ae4be4bfd1d3a0\", \"media_blink_unittests\": \"1f50d41f59b701ed8a4361e2c678fede5fe4599e\", \"media_unittests\": \"03aa933f59e6e796468832f9d65eed2c0eac12ad\", \"message_center_unittests\": \"ceb301a7a4224ad1b79ef6c9aa64fafebb05c0df\", \"midi_unittests\": \"806b4f6b481d690ba397eefc8299e920aabff9e4\", \"mojo_core_unittests\": \"19501930761909f9a2dc66e5f038640dca795c62\", \"mojo_unittests\": \"09c84078031c849c996f477694cd0c74ed31cd1f\", \"nacl_loader_unittests\": \"6b660a5d99011937fd46a6ed983a783cbf766a33\", \"native_theme_unittests\": \"61fea70574a0ede955a363d0bd61b42736c76ea1\", \"net_unittests\": \"86dfa37e0c269a2f40a2635cd2f79ec3abec6c52\", \"notification_helper_unittests\": \"af62a73e05356cccf429fa23157b40b018717bae\", \"pdf_unittests\": \"3216ac882f2848ece91315c7d4bd3ba4ca5ba079\", \"perfetto_unittests\": \"67327832b9d178166a745f7c4905751ae5d4144e\", \"ppapi_unittests\": \"4726cebb66c658feeabc009b95372f24ff341a94\", \"printing_unittests\": \"dcc59e2a86ee37a135b8d78ed2dc16022848bbf8\", \"remoting_unittests\": \"cebfffd5b4840d10457f35339ab202420ba7d346\", \"sbox_integration_tests\": \"be56f3e66968a0273b92354c28e94f781ea5c76f\", \"sbox_unittests\": \"ebbdcfed84b280a25f8a8f05ce6b0571b4498c4e\", \"sbox_validation_tests\": \"5b1f6785a0e202c160a932a0d96b671710dc02e9\", \"service_manager_unittests\": \"c651b5ecd123f81227d5def5b0c5fd6cfcd9adf5\", \"services_unittests\": \"1b2326fb7e50b9d778b00f705ecb042f65edf01c\", \"setup_unittests\": \"99c426eedca8600fc10b14eaf2d308616d6c89ef\", \"shell_dialogs_unittests\": \"b9377688f01d25509b5fad62a6d004b6303b067c\", \"skia_unittests\": \"18ead11d3706944cc2326e691efcf502a97a4dd1\", \"snapshot_unittests\": \"c1931f433afb3ee140ef079b8365b8933a1ea9f6\", \"sql_unittests\": \"b45de565cabf3f41cf03e4f4dcf579a0121ba3a4\", \"storage_unittests\": \"e56d9586c20b56b2db983d2710ffdd7caf71a252\", \"sync_integration_tests\": \"0f289494348947de0609d2e2e55455b41d3113f3\", \"traffic_annotation_auditor_unittests\": \"e02b203d0be6c4c522aee20986a6984fe4ac0337\", \"ui_base_unittests\": \"cf94a17b48a113229fb31b50914d6d59b62944fc\", \"ui_touch_selection_unittests\": \"4b22030c95d4cefd7369b52eb8fa0a0a98dd6456\", \"unit_tests\": \"17da69295c1923666e55518dc209c219b53f0502\", \"updater_tests\": \"ddf0924044c897970d41d9248d5939a13e2ef5bc\", \"url_unittests\": \"84cdd9e2933f621b7eb029d145ce4c3acd541a47\", \"views_unittests\": \"bf73428ab6a1776b963fcab57c2b63ecf71c9144\", \"viz_unittests\": \"ac66fce68030200a999260ef28249683117b9b86\", \"vr_common_unittests\": \"047993389466f5a01ae1b02b7a25a3d80e202770\", \"vr_pixeltests\": \"9482ca070ad9cbbf90711ddabc18aae1dde01383\", \"weblayer_browsertests\": \"a3feaf1e1e49dad6c3c50616a163a371e5461ebb\", \"weblayer_unittests\": \"e6c701fdc246a0baced0e11b00740cedef9f915f\", \"wm_unittests\": \"55aef6fb130b4923ef2de57e18ccb98c23a55169\", \"wtf_unittests\": \"c50cc85313eefe61cf37c6dc855db98fac6976c2\", \"zlib_unittests\": \"e628db89766469d0cdb585c401b421df55196a2b\", \"zucchini_unittests\": \"cdb5b6a1297b26bd6c43ad609c532815f0456100\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-6-gpyc\"], \"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\": [\"5657-0417a3f\"], \"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": "1608701040734141",
"status": "COMPLETED",
"status_changed_ts": "1608704302946436",
"tags": [
"build_address:luci.chromium.ci/win-asan/16400",
"builder:win-asan",
"buildset:commit/git/ffe46599e8a9e11905335f512e02dbcea9dd2f2e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ffe46599e8a9e11905335f512e02dbcea9dd2f2e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025578478817697312",
"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/8860148416775300640/+/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:50a6df5a05f6a210",
"user_agent:luci-scheduler"
],
"updated_ts": "1608704302951103",
"url": "https://ci.chromium.org/b/8860148416775300640",
"utcnow_ts": "1608704303581770"
}
}
[06:18:23.607] Invocation finished in 54m27.35685005s with status SUCCEEDED