[20:46:54.358] New invocation is queued and will start shortly
[20:46:55.596] Starting the invocation (attempt 1)
[20:46:55.647] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:46:55.647] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:46:55.647] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9024161456188053968",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"68d4c690e00a83aa9a5be698d768063f70f5b4de\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9024161456188053968",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/68d4c690e00a83aa9a5be698d768063f70f5b4de",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/68d4c690e00a83aa9a5be698d768063f70f5b4de",
"gitiles_ref:refs/heads/master"
]
}
[20:46:56.315] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610052415732829",
"id": "8858731394079260688",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"68d4c690e00a83aa9a5be698d768063f70f5b4de\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610052416120840",
"tags": [
"build_address:luci.chromium.ci/win-asan/16680",
"builder:win-asan",
"buildset:commit/git/68d4c690e00a83aa9a5be698d768063f70f5b4de",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/68d4c690e00a83aa9a5be698d768063f70f5b4de",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024161456188053968",
"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/8858731394079260688/+/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": "1610052416120954",
"url": "https://ci.chromium.org/b/8858731394079260688",
"utcnow_ts": "1610052416305724"
}
}
[20:46:56.315] Task URL: https://ci.chromium.org/b/8858731394079260688
[20:46:56.315] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:2:0) after 1m0s
[20:47:02.054] Received PubSub notification, asking Buildbucket for the build status
[20:47:02.161] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:56.469] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:2:0)
[20:47:56.469] Timer tick, asking Buildbucket for the build status
[20:47:56.601] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:56.601] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:4:0) after 1m0s
[20:48:56.665] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:4:0)
[20:48:56.665] Timer tick, asking Buildbucket for the build status
[20:48:56.754] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:56.754] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:5:0) after 1m0s
[20:49:56.995] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:5:0)
[20:49:56.995] Timer tick, asking Buildbucket for the build status
[20:49:57.112] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:57.112] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:6:0) after 1m0s
[20:50:57.136] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:6:0)
[20:50:57.136] Timer tick, asking Buildbucket for the build status
[20:50:57.252] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:57.252] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:7:0) after 1m0s
[20:51:57.423] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:7:0)
[20:51:57.423] Timer tick, asking Buildbucket for the build status
[20:51:57.520] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:57.520] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:8:0) after 1m0s
[20:52:57.614] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:8:0)
[20:52:57.614] Timer tick, asking Buildbucket for the build status
[20:52:58.073] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:58.073] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:9:0) after 1m0s
[20:53:58.144] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:9:0)
[20:53:58.144] Timer tick, asking Buildbucket for the build status
[20:53:58.393] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:58.393] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:10:0) after 1m0s
[20:54:58.535] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:10:0)
[20:54:58.535] Timer tick, asking Buildbucket for the build status
[20:54:58.615] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:58.615] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:11:0) after 1m0s
[20:55:58.641] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:11:0)
[20:55:58.641] Timer tick, asking Buildbucket for the build status
[20:55:58.730] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:58.730] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:12:0) after 1m0s
[20:56:58.795] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:12:0)
[20:56:58.795] Timer tick, asking Buildbucket for the build status
[20:56:59.006] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:59.007] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:13:0) after 1m0s
[20:57:59.097] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:13:0)
[20:57:59.097] Timer tick, asking Buildbucket for the build status
[20:57:59.163] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:59.163] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:14:0) after 1m0s
[20:58:59.207] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:14:0)
[20:58:59.207] Timer tick, asking Buildbucket for the build status
[20:58:59.311] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:59.311] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:15:0) after 1m0s
[20:59:59.361] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:15:0)
[20:59:59.361] Timer tick, asking Buildbucket for the build status
[20:59:59.452] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:59.452] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:16:0) after 1m0s
[21:00:59.437] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:16:0)
[21:00:59.437] Timer tick, asking Buildbucket for the build status
[21:00:59.598] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:59.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:17:0) after 1m0s
[21:01:59.654] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:17:0)
[21:01:59.654] Timer tick, asking Buildbucket for the build status
[21:01:59.778] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:59.778] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:18:0) after 1m0s
[21:02:59.783] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:18:0)
[21:02:59.783] Timer tick, asking Buildbucket for the build status
[21:02:59.899] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:59.899] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:19:0) after 1m0s
[21:03:59.987] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:19:0)
[21:03:59.987] Timer tick, asking Buildbucket for the build status
[21:04:00.167] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:00.167] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:20:0) after 1m0s
[21:05:00.285] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:20:0)
[21:05:00.285] Timer tick, asking Buildbucket for the build status
[21:05:00.676] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:00.676] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:21:0) after 1m0s
[21:06:00.701] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:21:0)
[21:06:00.701] Timer tick, asking Buildbucket for the build status
[21:06:00.834] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:00.834] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:22:0) after 1m0s
[21:07:00.935] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:22:0)
[21:07:00.935] Timer tick, asking Buildbucket for the build status
[21:07:01.573] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:01.573] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:23:0) after 1m0s
[21:08:01.659] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:23:0)
[21:08:01.659] Timer tick, asking Buildbucket for the build status
[21:08:02.530] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:02.530] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:24:0) after 1m0s
[21:09:02.612] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:24:0)
[21:09:02.612] Timer tick, asking Buildbucket for the build status
[21:09:02.804] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:02.804] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:25:0) after 1m0s
[21:10:02.884] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:25:0)
[21:10:02.884] Timer tick, asking Buildbucket for the build status
[21:10:03.089] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:03.089] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:26:0) after 1m0s
[21:11:03.224] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:26:0)
[21:11:03.224] Timer tick, asking Buildbucket for the build status
[21:11:03.603] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:03.603] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:27:0) after 1m0s
[21:12:03.638] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:27:0)
[21:12:03.638] Timer tick, asking Buildbucket for the build status
[21:12:03.797] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:03.797] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:28:0) after 1m0s
[21:13:03.837] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:28:0)
[21:13:03.837] Timer tick, asking Buildbucket for the build status
[21:13:04.357] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:04.357] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:29:0) after 1m0s
[21:14:04.440] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:29:0)
[21:14:04.440] Timer tick, asking Buildbucket for the build status
[21:14:04.651] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:04.651] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:30:0) after 1m0s
[21:15:04.722] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:30:0)
[21:15:04.722] Timer tick, asking Buildbucket for the build status
[21:15:04.886] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:04.886] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:31:0) after 1m0s
[21:16:05.000] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:31:0)
[21:16:05.001] Timer tick, asking Buildbucket for the build status
[21:16:05.325] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:05.325] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:32:0) after 1m0s
[21:17:05.395] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:32:0)
[21:17:05.395] Timer tick, asking Buildbucket for the build status
[21:17:06.584] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:06.584] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:33:0) after 1m0s
[21:18:06.625] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:33:0)
[21:18:06.625] Timer tick, asking Buildbucket for the build status
[21:18:06.787] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:06.787] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:34:0) after 1m0s
[21:19:06.810] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:34:0)
[21:19:06.810] Timer tick, asking Buildbucket for the build status
[21:19:06.895] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:06.895] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:35:0) after 1m0s
[21:20:06.921] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:35:0)
[21:20:06.921] Timer tick, asking Buildbucket for the build status
[21:20:07.006] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:07.006] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:36:0) after 1m0s
[21:21:07.098] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:36:0)
[21:21:07.098] Timer tick, asking Buildbucket for the build status
[21:21:07.209] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:07.209] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:37:0) after 1m0s
[21:22:07.241] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:37:0)
[21:22:07.241] Timer tick, asking Buildbucket for the build status
[21:22:07.667] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:07.667] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:38:0) after 1m0s
[21:23:07.714] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:38:0)
[21:23:07.714] Timer tick, asking Buildbucket for the build status
[21:23:08.267] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:08.267] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:39:0) after 1m0s
[21:24:08.335] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:39:0)
[21:24:08.335] Timer tick, asking Buildbucket for the build status
[21:24:08.444] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:08.444] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:40:0) after 1m0s
[21:25:08.474] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:40:0)
[21:25:08.474] Timer tick, asking Buildbucket for the build status
[21:25:08.566] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:08.566] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:41:0) after 1m0s
[21:26:08.605] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:41:0)
[21:26:08.605] Timer tick, asking Buildbucket for the build status
[21:26:08.689] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:08.689] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:42:0) after 1m0s
[21:27:08.725] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:42:0)
[21:27:08.725] Timer tick, asking Buildbucket for the build status
[21:27:09.019] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:09.019] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:43:0) after 1m0s
[21:28:09.156] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:43:0)
[21:28:09.156] Timer tick, asking Buildbucket for the build status
[21:28:09.783] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:09.783] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:44:0) after 1m0s
[21:29:09.888] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:44:0)
[21:29:09.888] Timer tick, asking Buildbucket for the build status
[21:29:10.092] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:10.092] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:45:0) after 1m0s
[21:30:10.198] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:45:0)
[21:30:10.198] Timer tick, asking Buildbucket for the build status
[21:30:10.337] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:10.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:46:0) after 1m0s
[21:31:10.438] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:46:0)
[21:31:10.438] Timer tick, asking Buildbucket for the build status
[21:31:10.563] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:10.563] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:47:0) after 1m0s
[21:32:10.668] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:47:0)
[21:32:10.668] Timer tick, asking Buildbucket for the build status
[21:32:10.844] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:10.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:48:0) after 1m0s
[21:33:10.890] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:48:0)
[21:33:10.890] Timer tick, asking Buildbucket for the build status
[21:33:11.393] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:11.393] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:49:0) after 1m0s
[21:34:11.453] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:49:0)
[21:34:11.453] Timer tick, asking Buildbucket for the build status
[21:34:11.968] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:11.968] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:50:0) after 1m0s
[21:35:12.041] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:50:0)
[21:35:12.041] Timer tick, asking Buildbucket for the build status
[21:35:12.446] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:12.446] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:51:0) after 1m0s
[21:36:12.517] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:51:0)
[21:36:12.517] Timer tick, asking Buildbucket for the build status
[21:36:12.650] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:12.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:52:0) after 1m0s
[21:37:12.704] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:52:0)
[21:37:12.704] Timer tick, asking Buildbucket for the build status
[21:37:12.814] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:12.814] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:53:0) after 1m0s
[21:38:12.850] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:53:0)
[21:38:12.850] Timer tick, asking Buildbucket for the build status
[21:38:13.022] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:13.022] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:54:0) after 1m0s
[21:39:13.108] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:54:0)
[21:39:13.108] Timer tick, asking Buildbucket for the build status
[21:39:13.238] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:13.238] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:55:0) after 1m0s
[21:40:13.409] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:55:0)
[21:40:13.409] Timer tick, asking Buildbucket for the build status
[21:40:13.720] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:13.720] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:56:0) after 1m0s
[21:41:13.760] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:56:0)
[21:41:13.760] Timer tick, asking Buildbucket for the build status
[21:41:14.003] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:14.003] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:57:0) after 1m0s
[21:42:14.022] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:57:0)
[21:42:14.022] Timer tick, asking Buildbucket for the build status
[21:42:14.486] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:14.486] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:58:0) after 1m0s
[21:43:14.538] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:58:0)
[21:43:14.538] Timer tick, asking Buildbucket for the build status
[21:43:17.267] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:17.267] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:59:0) after 1m0s
[21:44:17.467] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:59:0)
[21:44:17.467] Timer tick, asking Buildbucket for the build status
[21:44:18.200] Build 8858731394079260688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:18.200] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024161456188053968:60:0) after 1m0s
[21:44:29.450] Received PubSub notification, asking Buildbucket for the build status
[21:44:29.750] Build 8858731394079260688: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[21:44:29.750] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610055866889378",
"created_by": "project:chromium",
"created_ts": "1610052415732829",
"failure_reason": "BUILD_FAILURE",
"id": "8858731394079260688",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"68d4c690e00a83aa9a5be698d768063f70f5b4de\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**unit_tests** failed because of:\\n\\n- PredictionModelDownloadManagerTest.VerifiedCrxWithNoFilesShouldDeleteTempFile\"}, \"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\": \"68d4c690e00a83aa9a5be698d768063f70f5b4de\", \"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-7-w73f\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16680, \"clang_revision\": \"llvmorg-12-init-15676-g5c951623-1\", \"got_angle_revision\": \"d0a38010a14cf01b1a7b32143315108bd3334f46\", \"got_dawn_revision\": \"06aa1af28a72833fd59531763a0bee0338811f5b\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"68d4c690e00a83aa9a5be698d768063f70f5b4de\", \"got_revision_cp\": \"refs/heads/master@{#841175}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"7b2f938954180e8945f776d3e202f6a2bda1aaf9\", \"got_v8_revision\": \"d1da011fb05beda92d918cf7b378ddf7b7386c9b\", \"got_v8_revision_cp\": \"refs/heads/8.9.214@{#1}\", \"got_webrtc_revision\": \"fc6b87192db6d5e4fd6997814f00b6792cf16180\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32904}\", \"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\": \"68d4c690e00a83aa9a5be698d768063f70f5b4de\", \"swarm_hashes_refs/heads/master(at){#841175}_without_patch\": {\"absl_hardening_tests\": \"e0709def1d38071711db0b14ae2af50d29f3940e\", \"accessibility_unittests\": \"4f4835ef8480007cbdec8c16e346c4af4c53feb3\", \"angle_unittests\": \"9710da3955c9ac1e9094539fba45c113778acd6d\", \"app_shell_unittests\": \"56fcd7019ad843bb5bb5bdfc6e2f9c93123b50c5\", \"aura_unittests\": \"e262ae1e6681dab4e814a18c788794ab7305b344\", \"base_unittests\": \"087ab73cc4e773a6e538bb8601aca76451ef0cee\", \"base_util_unittests\": \"4cd1fcf4d479ba99f58ea0b80f6ba00a99e1598f\", \"blink_common_unittests\": \"40773f01e65861129fd266a7173cdfc2a29cb9d4\", \"blink_fuzzer_unittests\": \"91114339916a3d3efa3c38130c6953726d3612db\", \"blink_heap_unittests\": \"f6b25668164140e200f3313b439f0ccdb69f85c4\", \"blink_platform_unittests\": \"45624b01e32ff38e653de6e6cc549f405be24a33\", \"blink_unittests\": \"25b76fad853920e218d95d49e63222725c6f88ae\", \"boringssl_crypto_tests\": \"71cbdbe7f5636957f1e674e1bbc1ce8f23cf20de\", \"boringssl_ssl_tests\": \"344690f38b46f38e8d493a9f48f1fbb1b3d919b6\", \"browser_switcher_bho_unittests\": \"2f1c49e2a73c917f0314e89c9daf25e22862beb1\", \"browser_tests\": \"247d1794fd1badabefdb43d29ae85e4f153f8a57\", \"capture_unittests\": \"c9e1108a6e421b0952035e5f7ff89387210e495c\", \"cast_unittests\": \"61e2174d457393a821314ce2f45f13c158ccf4ac\", \"cc_unittests\": \"56cc943c2fd08543dc4bd4e26717b9dfe0c217d9\", \"chrome_app_unittests\": \"2b76ad78936e76f5d0b3fc136c771104c2e6931b\", \"chrome_cleaner_unittests\": \"a28ff56faa5151981c6e542a0730515a0c69bee5\", \"chrome_elf_unittests\": \"26bde3e3a32c9b7b7a01f0ca9612417d2bfdfd9b\", \"chromedriver_unittests\": \"17a56e014444951f4f3a9ea72c15741683bf05e7\", \"components_browsertests\": \"5a3c74c0ee60c6f13e2b2fdcb4402f8a3cb29a07\", \"components_unittests\": \"ecc817e6806c6ebcf4cde5f41eacdd52dbb47316\", \"compositor_unittests\": \"d2e5b12146ff885be13ce68259d139fdbbdff4d7\", \"content_browsertests\": \"07a0eef4c0ee83d0a66f15c455c231c9e0404a80\", \"content_unittests\": \"eb8987427eee54aa91bac12f87a21fe9aeee3d29\", \"courgette_unittests\": \"edccac9956da2beb12f47d2802e809319afc2772\", \"crashpad_tests\": \"84c5e5b24e694c9685dfee9a163bc3ebeb053112\", \"cronet_tests\": \"f8084ea3c306cc3c14bbf81e9c4a19310408bb93\", \"cronet_unittests\": \"472340e25bf9aa8a993660700698d7ecd7715ad1\", \"crypto_unittests\": \"098af2087a207ed63be849be941432aa0062d1f9\", \"delayloads_unittests\": \"7998f593d72af48457769279c97d301f81501fc7\", \"device_unittests\": \"cd6e7213a525b980b2bb1d8f466689b6526d5a0c\", \"display_unittests\": \"eb2d8ab843005149e278063765c34c033b793d21\", \"elevation_service_unittests\": \"811d620ce6fcc405d6f2a16d6250c88f4464b541\", \"events_unittests\": \"c8cffe779edebf982cdfd3dca2099e016e595df7\", \"extensions_browsertests\": \"286cdecfd5b5ca86da1f156fb601c0d937804c22\", \"extensions_unittests\": \"e23f847f915af799edbb02d7811c141d2172ac01\", \"filesystem_service_unittests\": \"add946787cbc0b161d40e6d24502fd75a3110f6e\", \"gcm_unit_tests\": \"7e7143e0c87942e6c012107de894053735cc643a\", \"gcp_unittests\": \"9ed87ad18442f7362b7c670bf3bed9ed1fc37207\", \"gfx_unittests\": \"f99b17f56ceab72a3b9e235b639a0f800d3cb6de\", \"gin_unittests\": \"4df735228b2e55b2cae81433a565f06715a2d23c\", \"google_apis_unittests\": \"5ed6a3020545459aaea4378869404b6480221990\", \"gpu_unittests\": \"ccb95087b99b31e1a00a9b50d4e61be0fc613a8d\", \"gwp_asan_unittests\": \"f8f6b66b6a9b3ad36f856e9187faa18d0bf24666\", \"headless_browsertests\": \"c39eca482063eca4b944afa848cdd627648abd0f\", \"headless_unittests\": \"b149a938e600a2eda15cea3bc998bea41ac935ab\", \"install_static_unittests\": \"c326f5cf62d8927da3fadd52ac928b575be90259\", \"installer_util_unittests\": \"8296240077f67c81d074183ad3b581d02509793b\", \"interactive_ui_tests\": \"95e18394e5a504bd95775d6727786d2aaaa0c383\", \"ipc_tests\": \"f829c0c97f7c0e2cffabe6b6b35b906f0182eb03\", \"jingle_unittests\": \"6432036f9e443e7adfc766225afa5c53a2d48548\", \"latency_unittests\": \"362feb62232bd0748736b8191d72fb183c97084b\", \"libjingle_xmpp_unittests\": \"d38712e75bd4cb1b53781d69a9220630d6b0b27b\", \"liburlpattern_unittests\": \"a22cab50547a21615323165a06e7dcc17c50d725\", \"media_blink_unittests\": \"dbf10194d40778671899bc439aa14f1acaf78c16\", \"media_unittests\": \"47189497b983e3c5271cfb2dee4d699f1dcb403e\", \"message_center_unittests\": \"61fae030b18157a75e18c38fd18d84087715d8da\", \"midi_unittests\": \"cbd505a5eef52346e17967cd29316bacae11c520\", \"mojo_core_unittests\": \"86583a1d0633aa4d3eb7ea7754da6a48b99379f3\", \"mojo_unittests\": \"56bbdccdf1ffd2896f7f55248b4194ce00fc62ec\", \"nacl_loader_unittests\": \"cc2e1cd108b062f1819fb413058a4fac5479c908\", \"native_theme_unittests\": \"1a1c2003592cda0a0b1d3ab7fd2b487dc171fdf6\", \"net_unittests\": \"94b4923d408d8500d270c8ceac0787e3d48846fc\", \"notification_helper_unittests\": \"863705463019f10d5f2d141c4197335eacc98834\", \"pdf_unittests\": \"4579cf8f81fe2521b1256d9f879571612c84a564\", \"perfetto_unittests\": \"13be38547d430f2034f127d3de15994a2f243725\", \"ppapi_unittests\": \"2ec122361847345997eba8aca1edd0524312a0f6\", \"printing_unittests\": \"3bbe22fa8b592c62ad4e16fd56fcb3d62b7a8355\", \"remoting_unittests\": \"6312f072482f271fda1a1bd69e49a0d0c0fd9350\", \"sbox_integration_tests\": \"d2243edef4696da44f4d184e6aadfc5a22bdd1b5\", \"sbox_unittests\": \"b3bcb9edea47bef307446af597313100d0598bbb\", \"sbox_validation_tests\": \"72e9415dce7e2f5497112ec4acd1e103f2597ae9\", \"service_manager_unittests\": \"8f1d90c20311eb13e2c685c023fa4d60417f3bf1\", \"services_unittests\": \"88baee79c8f75ab721b2a6b67879d6702505ba90\", \"setup_unittests\": \"f3ec184ec0da7e0cc407d5c16a2475a0dc9fb401\", \"shell_dialogs_unittests\": \"8dd9a94c0aa32a222384088cf4cb2758a20c54a0\", \"skia_unittests\": \"a49c71533666cccef8eb6cb32fbb90d4c46119a9\", \"snapshot_unittests\": \"c2db738f58a08426b0496e74a36677426657de75\", \"sql_unittests\": \"dae82f2ad9e19e2d32bc730970a517846462dc17\", \"storage_unittests\": \"f1e98ef5264dcf0b43f5dc70ae7e6ca86b20c5fa\", \"sync_integration_tests\": \"802fc1416a6cdb0bfc08dfd23e3f01a7e3ed6c05\", \"traffic_annotation_auditor_unittests\": \"5ec88d5eaa90ad50605eef380a279c04e3b7a71d\", \"ui_base_unittests\": \"96f718bdef323d41deaacd30c367f0be57df686e\", \"ui_touch_selection_unittests\": \"3e3b8310f59d9d4132411854313e97e1b152af83\", \"unit_tests\": \"c17797d2b40bca12ee8e1e4a2231356a79bb75f2\", \"updater_tests\": \"b204ffe83f2f6635e3952f863232207a0a2b07fc\", \"url_unittests\": \"49a4ac38b35ae5b502a453a7cedf9c9eec031a99\", \"views_unittests\": \"4c05f7b02f72b3306abfc07cd155e29b1710b771\", \"viz_unittests\": \"4936f428d02a794dc5f9b1d21afb84d8c3d00083\", \"vr_common_unittests\": \"e8739853ab407de386a2c5373c09612eed6b8ed6\", \"vr_pixeltests\": \"f441708c065ec06f6a098d0a4ec78dacf6c882bb\", \"weblayer_browsertests\": \"0c0e5c68a10ba97c84bafc23fb9f645055514e32\", \"weblayer_unittests\": \"6291f3fd5bd46224b9890414c3cbd3f04b271be3\", \"wm_unittests\": \"f9e24122c8ba30551ece75db0018938d30be3d09\", \"wtf_unittests\": \"d7ae9b6c485cd6f66d52c1c1482c839d4ca9042f\", \"zlib_unittests\": \"02d7435e883d31ab61a512dae91b46445482d8bc\", \"zucchini_unittests\": \"ab400f379afbd67aacbc080efe921f9087fca3f9\"}}, \"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-7-w73f\"], \"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\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**unit_tests** failed because of:\\n\\n- PredictionModelDownloadManagerTest.VerifiedCrxWithNoFilesShouldDeleteTempFile\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1610052421035462",
"status": "COMPLETED",
"status_changed_ts": "1610052421840846",
"tags": [
"build_address:luci.chromium.ci/win-asan/16680",
"builder:win-asan",
"buildset:commit/git/68d4c690e00a83aa9a5be698d768063f70f5b4de",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/68d4c690e00a83aa9a5be698d768063f70f5b4de",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024161456188053968",
"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/8858731394079260688/+/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:50f76bbd0c74ab10",
"user_agent:luci-scheduler"
],
"updated_ts": "1610055868928193",
"url": "https://ci.chromium.org/b/8858731394079260688",
"utcnow_ts": "1610055869722092"
}
}
[21:44:29.750] Invocation finished in 57m35.412030809s with status FAILED