[09:01:29.415] New invocation is queued and will start shortly
[09:01:31.033] Starting the invocation (attempt 1)
[09:01:31.093] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:01:31.093] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:01:31.093] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023662255304757680",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"4b1848135ff6d53d4252c9dcfcf844e308d323a3\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9023662255304757680",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/4b1848135ff6d53d4252c9dcfcf844e308d323a3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4b1848135ff6d53d4252c9dcfcf844e308d323a3",
"gitiles_ref:refs/heads/master"
]
}
[09:01:31.763] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610528491246558",
"id": "8858232192721647360",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4b1848135ff6d53d4252c9dcfcf844e308d323a3\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610528491646274",
"tags": [
"build_address:luci.chromium.ci/win-asan/16794",
"builder:win-asan",
"buildset:commit/git/4b1848135ff6d53d4252c9dcfcf844e308d323a3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4b1848135ff6d53d4252c9dcfcf844e308d323a3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023662255304757680",
"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/8858232192721647360/+/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": "1610528491646376",
"url": "https://ci.chromium.org/b/8858232192721647360",
"utcnow_ts": "1610528491757166"
}
}
[09:01:31.763] Task URL: https://ci.chromium.org/b/8858232192721647360
[09:01:31.763] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:2:0) after 1m0s
[09:01:37.158] Received PubSub notification, asking Buildbucket for the build status
[09:01:38.298] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:31.779] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:2:0)
[09:02:31.779] Timer tick, asking Buildbucket for the build status
[09:02:31.872] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:31.872] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:4:0) after 1m0s
[09:03:32.092] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:4:0)
[09:03:32.092] Timer tick, asking Buildbucket for the build status
[09:03:32.251] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:32.251] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:5:0) after 1m0s
[09:04:32.325] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:5:0)
[09:04:32.325] Timer tick, asking Buildbucket for the build status
[09:04:32.460] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:32.460] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:6:0) after 1m0s
[09:05:32.491] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:6:0)
[09:05:32.491] Timer tick, asking Buildbucket for the build status
[09:05:32.611] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:32.611] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:7:0) after 1m0s
[09:06:32.618] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:7:0)
[09:06:32.618] Timer tick, asking Buildbucket for the build status
[09:06:32.906] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:32.906] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:8:0) after 1m0s
[09:07:33.085] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:8:0)
[09:07:33.085] Timer tick, asking Buildbucket for the build status
[09:07:33.282] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:33.282] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:9:0) after 1m0s
[09:08:33.409] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:9:0)
[09:08:33.409] Timer tick, asking Buildbucket for the build status
[09:08:33.459] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:33.459] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:10:0) after 1m0s
[09:09:33.511] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:10:0)
[09:09:33.511] Timer tick, asking Buildbucket for the build status
[09:09:33.769] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:33.769] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:11:0) after 1m0s
[09:10:33.823] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:11:0)
[09:10:33.823] Timer tick, asking Buildbucket for the build status
[09:10:33.877] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:33.877] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:12:0) after 1m0s
[09:11:33.931] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:12:0)
[09:11:33.931] Timer tick, asking Buildbucket for the build status
[09:11:34.321] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:34.321] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:13:0) after 1m0s
[09:12:34.340] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:13:0)
[09:12:34.340] Timer tick, asking Buildbucket for the build status
[09:12:34.424] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:34.425] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:14:0) after 1m0s
[09:13:34.479] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:14:0)
[09:13:34.479] Timer tick, asking Buildbucket for the build status
[09:13:34.605] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:34.605] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:15:0) after 1m0s
[09:14:34.590] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:15:0)
[09:14:34.590] Timer tick, asking Buildbucket for the build status
[09:14:34.647] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:34.647] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:16:0) after 1m0s
[09:15:34.681] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:16:0)
[09:15:34.681] Timer tick, asking Buildbucket for the build status
[09:15:34.810] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:34.810] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:17:0) after 1m0s
[09:16:34.869] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:17:0)
[09:16:34.869] Timer tick, asking Buildbucket for the build status
[09:16:35.166] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:35.166] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:18:0) after 1m0s
[09:17:35.177] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:18:0)
[09:17:35.177] Timer tick, asking Buildbucket for the build status
[09:17:35.233] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:35.233] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:19:0) after 1m0s
[09:18:35.348] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:19:0)
[09:18:35.348] Timer tick, asking Buildbucket for the build status
[09:18:35.421] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:35.421] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:20:0) after 1m0s
[09:19:35.568] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:20:0)
[09:19:35.568] Timer tick, asking Buildbucket for the build status
[09:19:35.619] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:35.619] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:21:0) after 1m0s
[09:20:35.653] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:21:0)
[09:20:35.653] Timer tick, asking Buildbucket for the build status
[09:20:35.713] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:35.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:22:0) after 1m0s
[09:21:35.737] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:22:0)
[09:21:35.737] Timer tick, asking Buildbucket for the build status
[09:21:35.824] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:35.824] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:23:0) after 1m0s
[09:22:35.842] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:23:0)
[09:22:35.842] Timer tick, asking Buildbucket for the build status
[09:22:35.961] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:35.961] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:24:0) after 1m0s
[09:23:35.987] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:24:0)
[09:23:35.988] Timer tick, asking Buildbucket for the build status
[09:23:36.053] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:36.053] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:25:0) after 1m0s
[09:24:36.094] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:25:0)
[09:24:36.094] Timer tick, asking Buildbucket for the build status
[09:24:36.233] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:36.233] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:26:0) after 1m0s
[09:25:36.255] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:26:0)
[09:25:36.255] Timer tick, asking Buildbucket for the build status
[09:25:36.399] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:36.399] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:27:0) after 1m0s
[09:26:36.426] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:27:0)
[09:26:36.426] Timer tick, asking Buildbucket for the build status
[09:26:36.873] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:36.873] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:28:0) after 1m0s
[09:27:36.893] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:28:0)
[09:27:36.893] Timer tick, asking Buildbucket for the build status
[09:27:37.049] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:37.049] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:29:0) after 1m0s
[09:28:37.166] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:29:0)
[09:28:37.166] Timer tick, asking Buildbucket for the build status
[09:28:37.537] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:37.537] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:30:0) after 1m0s
[09:29:37.626] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:30:0)
[09:29:37.626] Timer tick, asking Buildbucket for the build status
[09:29:37.726] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:37.726] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:31:0) after 1m0s
[09:30:37.824] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:31:0)
[09:30:37.824] Timer tick, asking Buildbucket for the build status
[09:30:37.924] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:37.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:32:0) after 1m0s
[09:31:38.048] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:32:0)
[09:31:38.048] Timer tick, asking Buildbucket for the build status
[09:31:38.399] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:38.399] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:33:0) after 1m0s
[09:32:38.608] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:33:0)
[09:32:38.608] Timer tick, asking Buildbucket for the build status
[09:32:38.717] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:38.717] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:34:0) after 1m0s
[09:33:38.839] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:34:0)
[09:33:38.839] Timer tick, asking Buildbucket for the build status
[09:33:39.151] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:39.151] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:35:0) after 1m0s
[09:34:39.279] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:35:0)
[09:34:39.279] Timer tick, asking Buildbucket for the build status
[09:34:39.383] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:39.383] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:36:0) after 1m0s
[09:35:39.538] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:36:0)
[09:35:39.538] Timer tick, asking Buildbucket for the build status
[09:35:39.614] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:39.614] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:37:0) after 1m0s
[09:36:39.686] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:37:0)
[09:36:39.686] Timer tick, asking Buildbucket for the build status
[09:36:40.006] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:40.006] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:38:0) after 1m0s
[09:37:40.105] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:38:0)
[09:37:40.105] Timer tick, asking Buildbucket for the build status
[09:37:40.272] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:40.272] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:39:0) after 1m0s
[09:38:40.350] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:39:0)
[09:38:40.350] Timer tick, asking Buildbucket for the build status
[09:38:40.519] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:40.519] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:40:0) after 1m0s
[09:39:40.670] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:40:0)
[09:39:40.670] Timer tick, asking Buildbucket for the build status
[09:39:40.828] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:40.828] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:41:0) after 1m0s
[09:40:41.133] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:41:0)
[09:40:41.133] Timer tick, asking Buildbucket for the build status
[09:40:41.278] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:41.278] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:42:0) after 1m0s
[09:41:41.499] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:42:0)
[09:41:41.499] Timer tick, asking Buildbucket for the build status
[09:41:42.109] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:42.109] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:43:0) after 1m0s
[09:42:42.245] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:43:0)
[09:42:42.245] Timer tick, asking Buildbucket for the build status
[09:42:42.613] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:42.613] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:44:0) after 1m0s
[09:43:42.635] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:44:0)
[09:43:42.635] Timer tick, asking Buildbucket for the build status
[09:43:42.801] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:42.801] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:45:0) after 1m0s
[09:44:42.823] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:45:0)
[09:44:42.823] Timer tick, asking Buildbucket for the build status
[09:44:43.137] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:43.137] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:46:0) after 1m0s
[09:45:43.174] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:46:0)
[09:45:43.174] Timer tick, asking Buildbucket for the build status
[09:45:43.312] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:43.312] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:47:0) after 1m0s
[09:46:43.405] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:47:0)
[09:46:43.405] Timer tick, asking Buildbucket for the build status
[09:46:44.123] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:44.123] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:48:0) after 1m0s
[09:47:44.294] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:48:0)
[09:47:44.294] Timer tick, asking Buildbucket for the build status
[09:47:44.906] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:44.906] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:49:0) after 1m0s
[09:48:45.136] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:49:0)
[09:48:45.136] Timer tick, asking Buildbucket for the build status
[09:48:45.213] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:45.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:50:0) after 1m0s
[09:49:45.228] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:50:0)
[09:49:45.228] Timer tick, asking Buildbucket for the build status
[09:49:45.560] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:45.560] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:51:0) after 1m0s
[09:50:45.643] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:51:0)
[09:50:45.643] Timer tick, asking Buildbucket for the build status
[09:50:46.088] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:46.088] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:52:0) after 1m0s
[09:51:46.227] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:52:0)
[09:51:46.227] Timer tick, asking Buildbucket for the build status
[09:51:46.336] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:46.336] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:53:0) after 1m0s
[09:52:46.400] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:53:0)
[09:52:46.400] Timer tick, asking Buildbucket for the build status
[09:52:46.568] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:46.568] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:54:0) after 1m0s
[09:53:46.688] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:54:0)
[09:53:46.688] Timer tick, asking Buildbucket for the build status
[09:53:46.906] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:46.906] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:55:0) after 1m0s
[09:54:46.991] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:55:0)
[09:54:46.991] Timer tick, asking Buildbucket for the build status
[09:54:47.082] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:47.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:56:0) after 1m0s
[09:55:47.166] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:56:0)
[09:55:47.166] Timer tick, asking Buildbucket for the build status
[09:55:47.506] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:47.506] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:57:0) after 1m0s
[09:56:47.625] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:57:0)
[09:56:47.625] Timer tick, asking Buildbucket for the build status
[09:56:47.705] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:47.705] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:58:0) after 1m0s
[09:57:47.787] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:58:0)
[09:57:47.787] Timer tick, asking Buildbucket for the build status
[09:57:47.888] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:47.888] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:59:0) after 1m0s
[09:58:47.972] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:59:0)
[09:58:47.972] Timer tick, asking Buildbucket for the build status
[09:58:48.751] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:48.751] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:60:0) after 1m0s
[09:59:48.849] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:60:0)
[09:59:48.849] Timer tick, asking Buildbucket for the build status
[09:59:48.912] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:48.912] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:61:0) after 1m0s
[10:00:49.058] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:61:0)
[10:00:49.058] Timer tick, asking Buildbucket for the build status
[10:00:49.137] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:49.137] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:62:0) after 1m0s
[10:01:49.243] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:62:0)
[10:01:49.243] Timer tick, asking Buildbucket for the build status
[10:01:49.363] Build 8858232192721647360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:49.363] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023662255304757680:63:0) after 1m0s
[10:02:07.253] Received PubSub notification, asking Buildbucket for the build status
[10:02:07.333] Build 8858232192721647360: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[10:02:07.333] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610532126232066",
"created_by": "project:chromium",
"created_ts": "1610528491246558",
"failure_reason": "BUILD_FAILURE",
"id": "8858232192721647360",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4b1848135ff6d53d4252c9dcfcf844e308d323a3\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- MSE_ExternalClearKey/EncryptedMediaTest.FrameSizeChangeVideo/0\"}, \"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\": \"4b1848135ff6d53d4252c9dcfcf844e308d323a3\", \"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-8-wwgq\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16794, \"clang_revision\": \"llvmorg-12-init-16296-g5e476061-1\", \"got_angle_revision\": \"2ed9671a0dddaab9c2582b62d3e479895ee0d333\", \"got_dawn_revision\": \"7faa362ea9c2bffbb8e9cc2e2d9f4f4af2fe4dde\", \"got_nacl_revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"got_revision\": \"4b1848135ff6d53d4252c9dcfcf844e308d323a3\", \"got_revision_cp\": \"refs/heads/master@{#842934}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"3549479dc4ccd92ef3b9e179080dcec1a430b01d\", \"got_v8_revision\": \"d98dd8de5def4e22ea8925098dc705b2986ec25e\", \"got_v8_revision_cp\": \"refs/heads/8.9.260@{#1}\", \"got_webrtc_revision\": \"1c5e63e5451374783aaf0259f5c23d3688a5b2ff\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32938}\", \"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\": \"4b1848135ff6d53d4252c9dcfcf844e308d323a3\", \"swarm_hashes_refs/heads/master(at){#842934}_without_patch\": {\"absl_hardening_tests\": \"1ef04d65afd764da8e896aeba26aada62e6909f3\", \"accessibility_unittests\": \"7b293b4bdc40ab529fc9b94693ed82192319fa15\", \"angle_unittests\": \"55360ef4f7ede6186ee037170e24cdec374213f5\", \"app_shell_unittests\": \"10fb11b1dbd75d6a5eae7cf3f575556ba5ed09fe\", \"aura_unittests\": \"6cb350b164ecc9ca3b2ce04c3d201b931114506c\", \"base_unittests\": \"59683a84ce2848d2ffcec8d9342c48c940455c9f\", \"base_util_unittests\": \"3737961d0c8907433d49c3c79757dfa42e8e044e\", \"blink_common_unittests\": \"16f9ca162470e2ad378128c44aa7603517d2397f\", \"blink_fuzzer_unittests\": \"64f231d0e9f86ac69cf5f8367c6aa4a31e1596f9\", \"blink_heap_unittests\": \"4fa4b05d31a07a9c74b4b9d0f23017467b7fdb96\", \"blink_platform_unittests\": \"150257f181913f4d4733a2885fc0f90475960ef8\", \"blink_unittests\": \"74e9d01b1bf307c20cee3f1205c2d664dfbe6154\", \"boringssl_crypto_tests\": \"5ee30f498c0f5848954b33a14a1e19c67cec788d\", \"boringssl_ssl_tests\": \"ee8da5a346cba968462f7108f0783dfd697f9e1a\", \"browser_switcher_bho_unittests\": \"7b8bc711ffb6bead9b12b397a47bae67cb5f6cb3\", \"browser_tests\": \"254a13324300205f4986b445eee913d1ca98ce14\", \"capture_unittests\": \"24dbb5f0952082e32a22e56e08d43c16d9e6e043\", \"cast_unittests\": \"4ea453b608723b14d94235ee04989d05eb9e73d6\", \"cc_unittests\": \"0f907fc722e228b720b035c3bc361c57ace334bd\", \"chrome_app_unittests\": \"d88ad1dc6ba4b9e8f5b682776c98af0cbac6655f\", \"chrome_cleaner_unittests\": \"b8c44e9a53bb99c2ce40ff5984e6b52d770b882a\", \"chrome_elf_unittests\": \"e50ab3518a9c8f3f8ea4e4f598ef3cb4b4e7c0e8\", \"chromedriver_unittests\": \"4c40d6df2a1aaa0f3363b96d8e545a8eb2afdb2b\", \"components_browsertests\": \"f872f6fe3955c4968857f2a266d6f858dd5e4af3\", \"components_unittests\": \"4cc72658859da1c5cde75357af581d1ceafef3c9\", \"compositor_unittests\": \"19f720e4365615aff5491f4e0fa2a8327cc1e80b\", \"content_browsertests\": \"bc51161aac03b269538b53f616f936ee46716cc0\", \"content_unittests\": \"1fd50f94819c36b4c7b1c5d31ff302209615c2cc\", \"courgette_unittests\": \"098be4bf12255bab4f09da15b9478f91f5aa7040\", \"crashpad_tests\": \"361cc0081fa38be3532c6c97962a28b6e246ccea\", \"cronet_tests\": \"ea55d73aa0410d382b0be9c06df23cfa33e7def5\", \"cronet_unittests\": \"4132a5d142d76905ce9f4a42e0bc4692052c9871\", \"crypto_unittests\": \"adb7ab3f67626e0925d8d0be0410ea6582422342\", \"delayloads_unittests\": \"2f1a061352a5229cf1611025e9289b1d44b1f307\", \"device_unittests\": \"fee5759aba2b823e6252f965f467e06b1eaf463b\", \"display_unittests\": \"b1b5695fb0ce96f04319ea96f3036ff573ab8fb4\", \"elevation_service_unittests\": \"69e05065d7aa72b351301fe370a7ad383baec599\", \"events_unittests\": \"c9588a7896b9543ef811169508094931545a3dea\", \"extensions_browsertests\": \"9c52119fae489bfea2f5d1f18f5e738a38d993d4\", \"extensions_unittests\": \"e03c1609440d74d1f596ee4868419a88308be6c9\", \"filesystem_service_unittests\": \"e520f364def20584f971b93ea66a5b33ec923c37\", \"gcm_unit_tests\": \"f480005d4b4ebf3be6ca1a255ec32540c5751600\", \"gcp_unittests\": \"4c2c7618e99f2ff051706c8a52efd539006a9d38\", \"gfx_unittests\": \"eb05b5c26403b820de617102b1aed3829634e20c\", \"gin_unittests\": \"0d6452e4521a36918007ce4fc00cf028e7277321\", \"google_apis_unittests\": \"37e70d21d640fb5c3ef57a4609181f92d0bd339c\", \"gpu_unittests\": \"666bccfc0bb351b39c63ea81788f82ab84c2ecd4\", \"gwp_asan_unittests\": \"15a1f0574f29e720be5b43fa47ee06d9e0434f18\", \"headless_browsertests\": \"b5887527aa45ec385699b48d906fd95f6ea58804\", \"headless_unittests\": \"203eea177d5a2eb8ff4b8963e7d71b61f44152fb\", \"install_static_unittests\": \"665d747ee0c6b437221113486535f95d2f306097\", \"installer_util_unittests\": \"1454335c79bf651e4bcda9ccf2341e8a59c52bde\", \"interactive_ui_tests\": \"dce7813e05639d0d33e4162bc7da0cb614c2e0e4\", \"ipc_tests\": \"e623ee383a4e525ebf2d8d9c6f6225f71841bfa1\", \"jingle_unittests\": \"02ea5b780522a99d2f4b19889a86c169a27bba71\", \"latency_unittests\": \"4a545a948e5847582ce7f1583826b232969db3c1\", \"libjingle_xmpp_unittests\": \"93b6af5404d60342d6fafa3f75628cd13d783970\", \"liburlpattern_unittests\": \"62e961b1330c1644b0d3affbb61d654378d011af\", \"media_blink_unittests\": \"c9d8ad4bf6fcd2e924232a0d1b31094f4fd5e145\", \"media_unittests\": \"0ad88163ec1ee736988e842b00fa546cd61f1980\", \"message_center_unittests\": \"5df5216e534c7ebf934983dc6fe83ea4473db78d\", \"midi_unittests\": \"5b17e444f83f7d2aadeacf9f3f870e20c7cf736c\", \"mojo_core_unittests\": \"a00b4b89d31be2ef0e6e41c059f16e5c5d3bc3ac\", \"mojo_unittests\": \"468411093fab3e0ab71ccc334ee2534d4bc3b6a8\", \"nacl_loader_unittests\": \"b8a45baed18173aa9766a86576441df00b95d067\", \"native_theme_unittests\": \"399cec9421f8bbe18a63b7d09abaf086a9c55dfd\", \"net_unittests\": \"bbfd42d9d551678c58cf2b497f0ea92856be398a\", \"notification_helper_unittests\": \"72f3f9a3b42fb671049f67f44337d2a10f2e6104\", \"pdf_unittests\": \"7bf7361cbb8b8f6bd784d03752590d192bb696fe\", \"perfetto_unittests\": \"390f48362389f5bdccdb0cfb1480c57c5f2cab07\", \"ppapi_unittests\": \"e6b0a5195282f420acfe102a8af33b61cd63a6c8\", \"printing_unittests\": \"f458c2aaf86aadf973962983982b47575744ed8d\", \"remoting_unittests\": \"917d4e78752f1913d621aa445f7233dfa2e262f1\", \"sbox_integration_tests\": \"63f3f07972685db3e67474c11c9144490ba3d089\", \"sbox_unittests\": \"013a8f0f93a07a08be956ee47c608e8d190523b1\", \"sbox_validation_tests\": \"80bb28f3ae967686eab469f9cfb08f4c0a6ac8dc\", \"service_manager_unittests\": \"30f196599f990dee7da8ce2569dfb9821b4728e3\", \"services_unittests\": \"85b1582a6ede28b57cc61d01dc26925aa4dba60d\", \"setup_unittests\": \"1e41e24dc536eea73640249f4058b16899626ebf\", \"shell_dialogs_unittests\": \"3d99ba838e5c2ff9489b8e83bb7117f332cd1250\", \"skia_unittests\": \"302679d75f48a4d8ca61f7268fb15d512005a766\", \"snapshot_unittests\": \"8c294a31940f011792967c4f6fd811d756a6f9bf\", \"sql_unittests\": \"6385b5245ef97c28b769d16b2019b293e544e5ff\", \"storage_unittests\": \"3c4c11d0f4f0d34adc8cf41efe758f606fd715af\", \"sync_integration_tests\": \"b9db991ca8e5533004f533cebd743279724f43ce\", \"traffic_annotation_auditor_unittests\": \"627fa665b562c32cd1eb45d7cb6ce2dd50852826\", \"ui_base_unittests\": \"b5ea8b5897a956cea4c3f15e505654fba391aa45\", \"ui_touch_selection_unittests\": \"b549fc0e4a211e68f7db05163db4e4ad2abc2fe5\", \"unit_tests\": \"e7b84dfd56929694f03bfe4afa91ead7b61e4fdb\", \"updater_tests\": \"2592987656b9638f075e25a870e614bc274d7a05\", \"url_unittests\": \"9d1001babf56d179435c7af8624eb9a15bf9a6e1\", \"views_unittests\": \"810586e5efe0f337c7e152e54e5d22e81c422412\", \"viz_unittests\": \"53eff4b5283596f5bdc0279b7fd22a70631e628f\", \"vr_common_unittests\": \"966e49d8711fa0750068f1c3aecf5ff85e314a25\", \"vr_pixeltests\": \"aafc662e71d83cf459bffb480e48c869d0339c0a\", \"weblayer_browsertests\": \"66ad7fe2e7b1cc789cda484b6a787ddd94ec1a7b\", \"weblayer_unittests\": \"8722812ce0c58566c0b2bff1ec83fc7b9fc9ddc9\", \"wm_unittests\": \"9fc0ea5ef538a45b4a3ac0b2a701ccb12e5ee32d\", \"wtf_unittests\": \"17db666c4cb3998f81ada084a12c7df7e4a86d61\", \"zlib_unittests\": \"eac41890e5ef08112a0c18acda29c480652e0f6d\", \"zucchini_unittests\": \"feb6cc47956e67508e476dd2ec0bd9e27a1b3704\"}}, \"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-8-wwgq\"], \"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\": [\"5661-c6f6250\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- MSE_ExternalClearKey/EncryptedMediaTest.FrameSizeChangeVideo/0\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1610528496316210",
"status": "COMPLETED",
"status_changed_ts": "1610528496773066",
"tags": [
"build_address:luci.chromium.ci/win-asan/16794",
"builder:win-asan",
"buildset:commit/git/4b1848135ff6d53d4252c9dcfcf844e308d323a3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4b1848135ff6d53d4252c9dcfcf844e308d323a3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023662255304757680",
"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/8858232192721647360/+/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:5113cc1310ea4a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1610532126515255",
"url": "https://ci.chromium.org/b/8858232192721647360",
"utcnow_ts": "1610532127323057"
}
}
[10:02:07.333] Invocation finished in 1h0m37.933822735s with status FAILED