[21:55:49.581] New invocation is queued and will start shortly
[21:55:50.656] Starting the invocation (attempt 1)
[21:55:50.691] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:55:50.691] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:55:50.691] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9027780998743835776",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"e7af8bb4c647163be8d94ebc10db1b696dfb1c19\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9027780998743835776",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/e7af8bb4c647163be8d94ebc10db1b696dfb1c19",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e7af8bb4c647163be8d94ebc10db1b696dfb1c19",
"gitiles_ref:refs/heads/master"
]
}
[21:55:51.420] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1606600550777271",
"id": "8862350936826915328",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e7af8bb4c647163be8d94ebc10db1b696dfb1c19\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1606600551285054",
"tags": [
"build_address:luci.chromium.ci/win-asan/15917",
"builder:win-asan",
"buildset:commit/git/e7af8bb4c647163be8d94ebc10db1b696dfb1c19",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e7af8bb4c647163be8d94ebc10db1b696dfb1c19",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027780998743835776",
"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/8862350936826915328/+/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": "1606600551285160",
"url": "https://ci.chromium.org/b/8862350936826915328",
"utcnow_ts": "1606600551410445"
}
}
[21:55:51.420] Task URL: https://ci.chromium.org/b/8862350936826915328
[21:55:51.420] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:2:0) after 1m0s
[21:56:35.399] Received PubSub notification, asking Buildbucket for the build status
[21:56:35.477] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:51.666] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:2:0)
[21:56:51.666] Timer tick, asking Buildbucket for the build status
[21:56:51.757] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:51.757] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:4:0) after 1m0s
[21:57:51.941] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:4:0)
[21:57:51.941] Timer tick, asking Buildbucket for the build status
[21:57:52.312] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:52.312] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:5:0) after 1m0s
[21:58:52.435] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:5:0)
[21:58:52.435] Timer tick, asking Buildbucket for the build status
[21:58:52.575] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:52.575] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:6:0) after 1m0s
[21:59:52.626] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:6:0)
[21:59:52.626] Timer tick, asking Buildbucket for the build status
[21:59:52.846] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:52.846] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:7:0) after 1m0s
[22:00:52.864] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:7:0)
[22:00:52.864] Timer tick, asking Buildbucket for the build status
[22:00:52.944] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:52.944] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:8:0) after 1m0s
[22:01:52.990] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:8:0)
[22:01:52.990] Timer tick, asking Buildbucket for the build status
[22:01:53.125] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:53.125] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:9:0) after 1m0s
[22:02:53.194] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:9:0)
[22:02:53.194] Timer tick, asking Buildbucket for the build status
[22:02:53.280] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:53.280] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:10:0) after 1m0s
[22:03:53.338] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:10:0)
[22:03:53.338] Timer tick, asking Buildbucket for the build status
[22:03:53.453] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:53.453] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:11:0) after 1m0s
[22:04:53.519] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:11:0)
[22:04:53.519] Timer tick, asking Buildbucket for the build status
[22:04:53.639] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:53.639] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:12:0) after 1m0s
[22:05:53.710] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:12:0)
[22:05:53.710] Timer tick, asking Buildbucket for the build status
[22:05:53.950] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:53.950] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:13:0) after 1m0s
[22:06:54.000] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:13:0)
[22:06:54.000] Timer tick, asking Buildbucket for the build status
[22:06:54.114] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:54.114] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:14:0) after 1m0s
[22:07:54.133] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:14:0)
[22:07:54.133] Timer tick, asking Buildbucket for the build status
[22:07:54.381] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:54.381] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:15:0) after 1m0s
[22:08:54.403] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:15:0)
[22:08:54.403] Timer tick, asking Buildbucket for the build status
[22:08:55.018] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:55.018] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:16:0) after 1m0s
[22:09:55.087] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:16:0)
[22:09:55.087] Timer tick, asking Buildbucket for the build status
[22:09:55.318] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:55.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:17:0) after 1m0s
[22:10:55.452] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:17:0)
[22:10:55.452] Timer tick, asking Buildbucket for the build status
[22:10:55.669] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:55.669] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:18:0) after 1m0s
[22:11:55.737] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:18:0)
[22:11:55.737] Timer tick, asking Buildbucket for the build status
[22:11:55.943] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:55.943] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:19:0) after 1m0s
[22:12:55.963] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:19:0)
[22:12:55.963] Timer tick, asking Buildbucket for the build status
[22:12:56.569] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:56.569] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:20:0) after 1m0s
[22:13:56.551] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:20:0)
[22:13:56.551] Timer tick, asking Buildbucket for the build status
[22:13:56.913] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:56.913] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:21:0) after 1m0s
[22:14:56.923] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:21:0)
[22:14:56.923] Timer tick, asking Buildbucket for the build status
[22:14:57.040] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:57.040] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:22:0) after 1m0s
[22:15:57.104] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:22:0)
[22:15:57.104] Timer tick, asking Buildbucket for the build status
[22:15:57.603] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:57.603] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:23:0) after 1m0s
[22:16:57.630] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:23:0)
[22:16:57.630] Timer tick, asking Buildbucket for the build status
[22:16:57.933] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:57.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:24:0) after 1m0s
[22:17:58.026] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:24:0)
[22:17:58.026] Timer tick, asking Buildbucket for the build status
[22:17:58.160] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:58.160] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:25:0) after 1m0s
[22:18:58.236] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:25:0)
[22:18:58.236] Timer tick, asking Buildbucket for the build status
[22:18:58.363] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:58.363] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:26:0) after 1m0s
[22:19:58.384] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:26:0)
[22:19:58.384] Timer tick, asking Buildbucket for the build status
[22:19:58.483] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:58.483] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:27:0) after 1m0s
[22:20:58.505] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:27:0)
[22:20:58.505] Timer tick, asking Buildbucket for the build status
[22:20:58.686] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:58.686] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:28:0) after 1m0s
[22:21:58.765] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:28:0)
[22:21:58.765] Timer tick, asking Buildbucket for the build status
[22:21:58.849] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:58.849] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:29:0) after 1m0s
[22:22:59.453] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:29:0)
[22:22:59.453] Timer tick, asking Buildbucket for the build status
[22:22:59.634] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:59.634] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:30:0) after 1m0s
[22:23:59.898] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:30:0)
[22:23:59.898] Timer tick, asking Buildbucket for the build status
[22:24:00.134] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:00.134] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:31:0) after 1m0s
[22:25:00.286] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:31:0)
[22:25:00.286] Timer tick, asking Buildbucket for the build status
[22:25:00.583] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:00.583] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:32:0) after 1m0s
[22:26:00.787] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:32:0)
[22:26:00.787] Timer tick, asking Buildbucket for the build status
[22:26:01.294] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:01.294] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:33:0) after 1m0s
[22:27:01.335] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:33:0)
[22:27:01.335] Timer tick, asking Buildbucket for the build status
[22:27:01.612] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:01.612] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:34:0) after 1m0s
[22:28:02.199] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:34:0)
[22:28:02.199] Timer tick, asking Buildbucket for the build status
[22:28:02.306] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:02.306] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:35:0) after 1m0s
[22:29:02.519] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:35:0)
[22:29:02.519] Timer tick, asking Buildbucket for the build status
[22:29:02.936] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:02.936] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:36:0) after 1m0s
[22:30:03.067] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:36:0)
[22:30:03.067] Timer tick, asking Buildbucket for the build status
[22:30:03.450] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:03.450] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:37:0) after 1m0s
[22:31:03.610] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:37:0)
[22:31:03.610] Timer tick, asking Buildbucket for the build status
[22:31:03.822] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:03.822] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:38:0) after 1m0s
[22:32:03.913] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:38:0)
[22:32:03.914] Timer tick, asking Buildbucket for the build status
[22:32:04.026] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:04.026] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:39:0) after 1m0s
[22:33:04.093] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:39:0)
[22:33:04.093] Timer tick, asking Buildbucket for the build status
[22:33:04.426] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:04.426] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:40:0) after 1m0s
[22:34:04.448] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:40:0)
[22:34:04.448] Timer tick, asking Buildbucket for the build status
[22:34:04.581] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:04.581] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:41:0) after 1m0s
[22:35:04.695] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:41:0)
[22:35:04.695] Timer tick, asking Buildbucket for the build status
[22:35:04.851] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:04.851] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:42:0) after 1m0s
[22:36:04.967] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:42:0)
[22:36:04.967] Timer tick, asking Buildbucket for the build status
[22:36:05.178] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:05.178] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:43:0) after 1m0s
[22:37:05.224] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:43:0)
[22:37:05.224] Timer tick, asking Buildbucket for the build status
[22:37:05.364] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:05.364] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:44:0) after 1m0s
[22:38:05.419] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:44:0)
[22:38:05.419] Timer tick, asking Buildbucket for the build status
[22:38:05.751] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:05.751] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:45:0) after 1m0s
[22:39:05.835] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:45:0)
[22:39:05.835] Timer tick, asking Buildbucket for the build status
[22:39:05.905] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:05.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:46:0) after 1m0s
[22:40:05.985] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:46:0)
[22:40:05.985] Timer tick, asking Buildbucket for the build status
[22:40:06.104] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:06.104] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:47:0) after 1m0s
[22:41:06.124] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:47:0)
[22:41:06.124] Timer tick, asking Buildbucket for the build status
[22:41:06.258] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:06.258] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:48:0) after 1m0s
[22:42:06.277] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:48:0)
[22:42:06.277] Timer tick, asking Buildbucket for the build status
[22:42:06.624] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:06.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:49:0) after 1m0s
[22:43:06.598] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:49:0)
[22:43:06.598] Timer tick, asking Buildbucket for the build status
[22:43:06.668] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:06.668] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:50:0) after 1m0s
[22:44:06.881] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:50:0)
[22:44:06.881] Timer tick, asking Buildbucket for the build status
[22:44:06.989] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:06.989] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:51:0) after 1m0s
[22:45:07.061] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:51:0)
[22:45:07.061] Timer tick, asking Buildbucket for the build status
[22:45:07.176] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:07.176] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:52:0) after 1m0s
[22:46:07.432] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:52:0)
[22:46:07.432] Timer tick, asking Buildbucket for the build status
[22:46:07.548] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:07.548] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:53:0) after 1m0s
[22:47:07.584] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:53:0)
[22:47:07.584] Timer tick, asking Buildbucket for the build status
[22:47:07.656] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:07.656] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:54:0) after 1m0s
[22:48:07.746] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:54:0)
[22:48:07.746] Timer tick, asking Buildbucket for the build status
[22:48:07.825] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:07.825] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:55:0) after 1m0s
[22:49:07.870] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:55:0)
[22:49:07.870] Timer tick, asking Buildbucket for the build status
[22:49:08.064] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:08.064] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:56:0) after 1m0s
[22:50:08.088] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:56:0)
[22:50:08.088] Timer tick, asking Buildbucket for the build status
[22:50:08.194] Build 8862350936826915328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:08.194] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027780998743835776:57:0) after 1m0s
[22:50:37.624] Received PubSub notification, asking Buildbucket for the build status
[22:50:37.732] Build 8862350936826915328: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[22:50:37.732] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1606603836232945",
"created_by": "project:chromium",
"created_ts": "1606600550777271",
"id": "8862350936826915328",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e7af8bb4c647163be8d94ebc10db1b696dfb1c19\"}}",
"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\": \"e7af8bb4c647163be8d94ebc10db1b696dfb1c19\", \"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-4-qhsy\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15917, \"clang_revision\": \"llvmorg-12-init-12083-g94e4ec64-1\", \"got_angle_revision\": \"59aa1ef1975ae2d263da864f111eaaa7cdb4f9f2\", \"got_dawn_revision\": \"d8bb0bb7c735173219d7a8ffec18eee1813ff302\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"e7af8bb4c647163be8d94ebc10db1b696dfb1c19\", \"got_revision_cp\": \"refs/heads/master@{#831698}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"9dff6a3bb5950edd890e5141f2d3d2f3b4ef351e\", \"got_v8_revision\": \"8d791670ba247239ef6ce9091183f11f8c5f0825\", \"got_v8_revision_cp\": \"refs/heads/8.9.65@{#1}\", \"got_webrtc_revision\": \"bcca3b08a90fccaa6dd8d86f6079f0ca0553ab4a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32715}\", \"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\": \"e7af8bb4c647163be8d94ebc10db1b696dfb1c19\", \"swarm_hashes_refs/heads/master(at){#831698}_without_patch\": {\"absl_hardening_tests\": \"9509b936e1e93db1fce16ea3976e7b806fa31537\", \"accessibility_unittests\": \"391baa3cd046b05196f8754201eeb2699131712a\", \"angle_unittests\": \"dcdf3196b9f347ab7ae5d8cd94f00f107010f3fc\", \"app_shell_unittests\": \"88fcf2b634079b1f14eb1a833bea0e95a753fdfa\", \"aura_unittests\": \"1691c47686db80c91d83d0c215e28df7c9bace73\", \"base_unittests\": \"5a01b95113ab79435dbfddcab08da0c597f9d643\", \"base_util_unittests\": \"e1edb8c892de0e4d36b3c3c2f659ff90d64b953e\", \"blink_common_unittests\": \"d983d8354a445d6119c2475b8ebe8e3c73925f2d\", \"blink_fuzzer_unittests\": \"2395ddc09cd592bdfecfac54f959f7b5875541db\", \"blink_heap_unittests\": \"a78b5905c89d88a85c1f0405178023168fa83d1d\", \"blink_platform_unittests\": \"7f3834d71a314913ae6e34159608780774180293\", \"blink_unittests\": \"0a8d96ba52aea0c56bd99b5a50087469f609a186\", \"boringssl_crypto_tests\": \"5ed56e4294391e789e6334bd8b4ef89d7801bb84\", \"boringssl_ssl_tests\": \"a27883cf305210b0c6c718c7620aa55d333fe9b6\", \"browser_switcher_bho_unittests\": \"aa682e26dbaf4ea03d16a9b39852f1951574bd4a\", \"browser_tests\": \"e440f771f76152410984b1ab80b174a8caa5c3cd\", \"capture_unittests\": \"8d0c9495287698a41d50392ef9130836dfe4aa62\", \"cast_unittests\": \"6c856b1270bfbef243a984671a10fb8514239466\", \"cc_unittests\": \"c20a42ae99f67d0237564c8507e73fe8fe68688b\", \"chrome_app_unittests\": \"560a4feec6292040e9b83345a1d9db3f0e86de2d\", \"chrome_cleaner_unittests\": \"e2cc2c3acc45b2afa342ef5b945b57f83ed45fff\", \"chrome_elf_unittests\": \"2f72dacee01c0279a78b227e2585cb3c56e855f9\", \"chromedriver_unittests\": \"d7cfc00b80f274349a2ed78ee4f84677c3cc38a0\", \"components_browsertests\": \"807931a0c03cfc779aa474ea553eb7fb8bd1900a\", \"components_unittests\": \"46caf9ecd7b216700bf68af96c425b33c671e2ed\", \"compositor_unittests\": \"619157971ed7769e7306444f76c3a23c2693a031\", \"content_browsertests\": \"c33ba17216e8ac4bea36533e91e71f7debc76c02\", \"content_unittests\": \"ae421a223bbed70971e048ce93eba39b2f796833\", \"courgette_unittests\": \"da8dcd05044e7e94cf2f9c858ac5f00f0abd6e74\", \"crashpad_tests\": \"4231be2777e5527e07ac488ad2db6c4b31d13bb9\", \"cronet_tests\": \"df26c56887d3cceb2272a39c7f9ef404352e56fb\", \"cronet_unittests\": \"4fc1669278d80d23f2c01e6e2edff287f8f3bf14\", \"crypto_unittests\": \"9e3e9ea2685b65b9eb88cbd73754461955da9d30\", \"delayloads_unittests\": \"3f41ee812d5ca708dd61c5edfd6e853faff2f483\", \"device_unittests\": \"695a98d43d640fa06691710b92484b2dc9484cda\", \"display_unittests\": \"53ec82f6709dfcc008547c67a5585ea474c2e778\", \"elevation_service_unittests\": \"cc86e0a8ee81b09758002cfb481e1fea24c0af5f\", \"events_unittests\": \"be28174d738f3e2cefd7c925af0db7663e145dfe\", \"extensions_browsertests\": \"1fd3566e23f40d117fb110eb7e31dcac7f0a9658\", \"extensions_unittests\": \"d64fbef94a637d1cb7cf932a7d8925548ddcebd5\", \"filesystem_service_unittests\": \"d1e63234bd7d7474b8acea6e3d983322a473cd05\", \"gcm_unit_tests\": \"cbe838d0ad94342aca582994e20d29d748bd3adb\", \"gcp_unittests\": \"35e734781c7d3f8e57929ee9bc75218553460b3e\", \"gfx_unittests\": \"efaf5998b461b1fc82c2b75c93b2fb4f0fbe3576\", \"gin_unittests\": \"49c893b177577058993259734c659e4e8e27065a\", \"google_apis_unittests\": \"fb1caf24abc10653464bf8534e48bda1ba25d5ec\", \"gpu_unittests\": \"5fa366bfc7afbf45bd28c43b1016e646f4c7491a\", \"gwp_asan_unittests\": \"8e1650ed78e7489fc10981a41cfb88d6d0553c0d\", \"headless_browsertests\": \"dc2d77877edb1bdaee61f3cf891ddc64652164db\", \"headless_unittests\": \"9b9459c4e5693b67891c591ce75ca776fdcc9a42\", \"install_static_unittests\": \"4f06fd7cae9a0785251a69a5418db0a43c0ae683\", \"installer_util_unittests\": \"716cfc14348ef3c296f63696cf183bba66fecf6b\", \"interactive_ui_tests\": \"fef04c3e311d17c071fc12b433448ca9a881705d\", \"ipc_tests\": \"a712b449ffedb6fc8300d2a58ea0e1bad4b3002f\", \"jingle_unittests\": \"678e662a1cf9e04f3cf88e38a152b3e70edf4231\", \"latency_unittests\": \"9edfaf8b59423640cf423bae5dc56120de657f12\", \"libjingle_xmpp_unittests\": \"e94ecbfca99448bdb459f44e8cd7d438a5898f71\", \"liburlpattern_unittests\": \"8becd94e286821bb962ffb78b9bd9710d57e7d26\", \"media_blink_unittests\": \"9066253cf7e99a2b1749604477ab0f44b5c0888f\", \"media_unittests\": \"6cd51dba2cb2323ab9944741059508af15f0f6e7\", \"message_center_unittests\": \"18062bb6f7901fedc8c8ef3c98003ab37b5e9971\", \"midi_unittests\": \"96e97a0e8e674ff4b84323203597ae712adbdb85\", \"mojo_core_unittests\": \"eab4948b2421d413e680f349c9c1c29333dfae06\", \"mojo_unittests\": \"59aa4a141673767cda26dc928a6fc566ef52f913\", \"nacl_loader_unittests\": \"7d780dde9aa005e09188a68e45caf53683aaf514\", \"native_theme_unittests\": \"37154487a98106d8cb12a39e3a78d24fa7bef259\", \"net_unittests\": \"bf7972a8141477417ab490df8dd2931a7725096a\", \"notification_helper_unittests\": \"df46e780aacb4d3fc006937105dd7acb2997e2f1\", \"pdf_unittests\": \"dc251072319ebb0c69e105bce747b932a5b8a40a\", \"perfetto_unittests\": \"70f78ad0db5bbbaf7d9f463b30801e25143462e8\", \"ppapi_unittests\": \"402ea77e5e11f1b58045308df3c467b67d898639\", \"printing_unittests\": \"b0f375c2c8dad9d7cf57d49780609d8e9314cf34\", \"remoting_unittests\": \"ee5c568d70fbb519280a78eef4eb8f3c35b4833a\", \"sbox_integration_tests\": \"68c581528953aeda37be48e488fee96c586ecbed\", \"sbox_unittests\": \"22c289e956baad152bdc70ab7664ab33c693f45f\", \"sbox_validation_tests\": \"b416829327ac687f70ff60f4b1a1611db6cf232e\", \"service_manager_unittests\": \"a4d4d78014dea33f478b5b3b315941bda99bc840\", \"services_unittests\": \"295737cf40ca1bedda48836efb2a71f86dc1b8d4\", \"setup_unittests\": \"f6f3549094747771ae0d66a62469479ad0959d52\", \"shell_dialogs_unittests\": \"57c34cf58f425e971fb620b6e23deecdb0b34b06\", \"skia_unittests\": \"2df010c370cb2792b0b44db8cc43b071c9ffd137\", \"snapshot_unittests\": \"ed40c188d288394d01fd11db9c0c5dc3e555e6ed\", \"sql_unittests\": \"7b0b80233e090efbfd19e40755d8efd562140154\", \"storage_unittests\": \"f51bc661f93c214951d7ee29dd7dcea7bc84c423\", \"sync_integration_tests\": \"07f883369992aa036a5250a37bf431b170c0d1a4\", \"traffic_annotation_auditor_unittests\": \"800382fced7db2a2f7ef124265eb1b415fb9e7ba\", \"ui_base_unittests\": \"654eeb583abaeeadea65114bfd3506414f1cf0f0\", \"ui_touch_selection_unittests\": \"caedce3cfcef480cafd2e0f30c307c3473fc85e0\", \"unit_tests\": \"c5aebe7dc850deb08e01e3e067521412aeae6aed\", \"updater_tests\": \"bc2aa8034b34d7975bde9b63941a87514770cb65\", \"url_unittests\": \"48fde9671b7bf1b2fb43f6edaa2e0e7f02a90166\", \"views_unittests\": \"d686b43f6724be578aaa0ecb4f37e6b1810d258e\", \"viz_unittests\": \"ea7ee2bbed4668d7e8dc113232bb1119b3bed566\", \"vr_common_unittests\": \"9fe47fcc85bf58300a59080cdc59dc5ad6e16add\", \"vr_pixeltests\": \"3d639e6195ea0f431a4e8739986628d27e0e6630\", \"weblayer_browsertests\": \"8631eefa2192e4b24c6ca90362fc93b63a7546cd\", \"weblayer_unittests\": \"2851ef9042ea8c152d060092451558b7f4c9cdf0\", \"wm_unittests\": \"b4d04832a7e1383ad3768c638806620675efce97\", \"wtf_unittests\": \"22ced23887b85c5744ef8230688fb64418c16333\", \"zlib_unittests\": \"074656959fac523a08e63074e3c144b21b39257d\", \"zucchini_unittests\": \"fd55c3644f4239fdc58c5ad73a1ee0f9fa7b528f\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_64c7a62a02e0da5ef76200bb7658267e40edf0984fe7c0f995e5999242969052_v2\", \"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-4-qhsy\"], \"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\": [\"5640-d0eb544\"], \"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": "1606600589919551",
"status": "COMPLETED",
"status_changed_ts": "1606603836624436",
"tags": [
"build_address:luci.chromium.ci/win-asan/15917",
"builder:win-asan",
"buildset:commit/git/e7af8bb4c647163be8d94ebc10db1b696dfb1c19",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e7af8bb4c647163be8d94ebc10db1b696dfb1c19",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027780998743835776",
"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/8862350936826915328/+/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:5029ac74beb55710",
"user_agent:luci-scheduler"
],
"updated_ts": "1606603836636498",
"url": "https://ci.chromium.org/b/8862350936826915328",
"utcnow_ts": "1606603837716607"
}
}
[22:50:37.732] Invocation finished in 54m48.166223672s with status SUCCEEDED