[06:55:48.233] New invocation is queued and will start shortly
[06:55:49.347] Starting the invocation (attempt 1)
[06:55:49.378] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:55:49.378] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:55:49.378] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019230911451016256",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"0599cb17daae838b0ee05943628d5d0998d39c8e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9019230911451016256",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/0599cb17daae838b0ee05943628d5d0998d39c8e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0599cb17daae838b0ee05943628d5d0998d39c8e",
"gitiles_ref:refs/heads/master"
]
}
[06:55:49.908] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614754549488548",
"id": "8853800849473855456",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0599cb17daae838b0ee05943628d5d0998d39c8e\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614754549804916",
"tags": [
"build_address:luci.chromium.ci/win-asan/17604",
"builder:win-asan",
"buildset:commit/git/0599cb17daae838b0ee05943628d5d0998d39c8e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0599cb17daae838b0ee05943628d5d0998d39c8e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019230911451016256",
"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/8853800849473855456/+/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": "1614754549805026",
"url": "https://ci.chromium.org/b/8853800849473855456",
"utcnow_ts": "1614754549903025"
}
}
[06:55:49.908] Task URL: https://ci.chromium.org/b/8853800849473855456
[06:55:49.908] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:2:0) after 1m0s
[06:55:53.340] Received PubSub notification, asking Buildbucket for the build status
[06:55:53.511] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:49.946] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:2:0)
[06:56:49.946] Timer tick, asking Buildbucket for the build status
[06:56:50.022] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:50.022] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:4:0) after 1m0s
[06:57:50.036] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:4:0)
[06:57:50.036] Timer tick, asking Buildbucket for the build status
[06:57:50.109] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:50.109] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:5:0) after 1m0s
[06:58:50.159] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:5:0)
[06:58:50.159] Timer tick, asking Buildbucket for the build status
[06:58:50.226] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:50.226] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:6:0) after 1m0s
[06:59:50.330] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:6:0)
[06:59:50.330] Timer tick, asking Buildbucket for the build status
[06:59:50.392] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:50.392] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:7:0) after 1m0s
[07:00:50.385] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:7:0)
[07:00:50.385] Timer tick, asking Buildbucket for the build status
[07:00:50.471] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:50.471] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:8:0) after 1m0s
[07:01:50.484] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:8:0)
[07:01:50.484] Timer tick, asking Buildbucket for the build status
[07:01:50.575] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:50.575] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:9:0) after 1m0s
[07:02:50.595] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:9:0)
[07:02:50.595] Timer tick, asking Buildbucket for the build status
[07:02:50.679] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:50.679] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:10:0) after 1m0s
[07:03:50.765] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:10:0)
[07:03:50.765] Timer tick, asking Buildbucket for the build status
[07:03:51.038] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:51.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:11:0) after 1m0s
[07:04:51.033] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:11:0)
[07:04:51.033] Timer tick, asking Buildbucket for the build status
[07:04:51.111] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:51.111] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:12:0) after 1m0s
[07:05:51.121] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:12:0)
[07:05:51.121] Timer tick, asking Buildbucket for the build status
[07:05:51.206] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:51.206] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:13:0) after 1m0s
[07:06:51.233] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:13:0)
[07:06:51.233] Timer tick, asking Buildbucket for the build status
[07:06:51.309] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:51.309] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:14:0) after 1m0s
[07:07:51.352] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:14:0)
[07:07:51.352] Timer tick, asking Buildbucket for the build status
[07:07:51.432] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:51.432] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:15:0) after 1m0s
[07:08:51.487] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:15:0)
[07:08:51.487] Timer tick, asking Buildbucket for the build status
[07:08:51.555] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:51.555] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:16:0) after 1m0s
[07:09:51.564] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:16:0)
[07:09:51.564] Timer tick, asking Buildbucket for the build status
[07:09:51.660] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:51.660] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:17:0) after 1m0s
[07:10:51.837] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:17:0)
[07:10:51.837] Timer tick, asking Buildbucket for the build status
[07:10:51.921] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:51.921] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:18:0) after 1m0s
[07:11:52.169] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:18:0)
[07:11:52.169] Timer tick, asking Buildbucket for the build status
[07:11:52.341] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:52.341] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:19:0) after 1m0s
[07:12:52.483] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:19:0)
[07:12:52.483] Timer tick, asking Buildbucket for the build status
[07:12:52.661] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:52.661] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:20:0) after 1m0s
[07:13:52.722] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:20:0)
[07:13:52.722] Timer tick, asking Buildbucket for the build status
[07:13:52.802] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:52.802] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:21:0) after 1m0s
[07:14:52.924] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:21:0)
[07:14:52.924] Timer tick, asking Buildbucket for the build status
[07:14:52.987] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:52.987] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:22:0) after 1m0s
[07:15:53.189] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:22:0)
[07:15:53.189] Timer tick, asking Buildbucket for the build status
[07:15:53.299] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:53.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:23:0) after 1m0s
[07:16:53.404] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:23:0)
[07:16:53.404] Timer tick, asking Buildbucket for the build status
[07:16:53.525] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:53.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:24:0) after 1m0s
[07:17:53.609] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:24:0)
[07:17:53.609] Timer tick, asking Buildbucket for the build status
[07:17:53.684] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:53.684] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:25:0) after 1m0s
[07:18:53.912] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:25:0)
[07:18:53.912] Timer tick, asking Buildbucket for the build status
[07:18:54.217] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:54.217] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:26:0) after 1m0s
[07:19:54.310] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:26:0)
[07:19:54.310] Timer tick, asking Buildbucket for the build status
[07:19:54.429] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:54.429] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:27:0) after 1m0s
[07:20:54.494] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:27:0)
[07:20:54.494] Timer tick, asking Buildbucket for the build status
[07:20:54.623] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:54.623] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:28:0) after 1m0s
[07:21:54.669] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:28:0)
[07:21:54.669] Timer tick, asking Buildbucket for the build status
[07:21:54.760] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:54.760] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:29:0) after 1m0s
[07:22:54.833] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:29:0)
[07:22:54.833] Timer tick, asking Buildbucket for the build status
[07:22:54.929] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:54.929] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:30:0) after 1m0s
[07:23:54.972] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:30:0)
[07:23:54.972] Timer tick, asking Buildbucket for the build status
[07:23:55.055] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:55.055] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:31:0) after 1m0s
[07:24:55.194] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:31:0)
[07:24:55.194] Timer tick, asking Buildbucket for the build status
[07:24:56.692] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:56.692] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:32:0) after 1m0s
[07:25:56.788] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:32:0)
[07:25:56.788] Timer tick, asking Buildbucket for the build status
[07:25:56.946] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:56.946] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:33:0) after 1m0s
[07:26:57.062] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:33:0)
[07:26:57.062] Timer tick, asking Buildbucket for the build status
[07:26:57.161] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:57.161] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:34:0) after 1m0s
[07:27:57.443] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:34:0)
[07:27:57.443] Timer tick, asking Buildbucket for the build status
[07:27:57.838] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:57.838] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:35:0) after 1m0s
[07:28:57.879] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:35:0)
[07:28:57.879] Timer tick, asking Buildbucket for the build status
[07:28:57.968] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:57.968] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:36:0) after 1m0s
[07:29:57.973] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:36:0)
[07:29:57.973] Timer tick, asking Buildbucket for the build status
[07:29:58.137] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:58.137] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:37:0) after 1m0s
[07:30:58.183] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:37:0)
[07:30:58.183] Timer tick, asking Buildbucket for the build status
[07:30:58.295] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:58.295] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:38:0) after 1m0s
[07:31:58.397] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:38:0)
[07:31:58.397] Timer tick, asking Buildbucket for the build status
[07:31:58.479] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:58.479] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:39:0) after 1m0s
[07:32:58.576] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:39:0)
[07:32:58.576] Timer tick, asking Buildbucket for the build status
[07:32:58.666] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:58.666] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:40:0) after 1m0s
[07:33:58.780] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:40:0)
[07:33:58.780] Timer tick, asking Buildbucket for the build status
[07:33:59.657] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:59.657] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:41:0) after 1m0s
[07:34:59.712] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:41:0)
[07:34:59.712] Timer tick, asking Buildbucket for the build status
[07:34:59.794] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:59.795] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:42:0) after 1m0s
[07:35:59.833] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:42:0)
[07:35:59.833] Timer tick, asking Buildbucket for the build status
[07:35:59.905] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:59.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:43:0) after 1m0s
[07:36:59.922] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:43:0)
[07:36:59.922] Timer tick, asking Buildbucket for the build status
[07:37:00.002] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:00.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:44:0) after 1m0s
[07:38:00.019] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:44:0)
[07:38:00.019] Timer tick, asking Buildbucket for the build status
[07:38:00.156] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:00.156] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:45:0) after 1m0s
[07:39:00.251] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:45:0)
[07:39:00.251] Timer tick, asking Buildbucket for the build status
[07:39:00.326] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:00.326] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:46:0) after 1m0s
[07:40:00.343] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:46:0)
[07:40:00.343] Timer tick, asking Buildbucket for the build status
[07:40:00.465] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:00.465] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:47:0) after 1m0s
[07:41:00.648] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:47:0)
[07:41:00.648] Timer tick, asking Buildbucket for the build status
[07:41:00.760] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:00.760] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:48:0) after 1m0s
[07:42:00.967] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:48:0)
[07:42:00.967] Timer tick, asking Buildbucket for the build status
[07:42:01.048] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:01.049] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:49:0) after 1m0s
[07:43:01.607] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:49:0)
[07:43:01.607] Timer tick, asking Buildbucket for the build status
[07:43:01.942] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:01.942] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:50:0) after 1m0s
[07:44:02.014] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:50:0)
[07:44:02.014] Timer tick, asking Buildbucket for the build status
[07:44:02.500] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:02.500] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:51:0) after 1m0s
[07:45:02.652] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:51:0)
[07:45:02.652] Timer tick, asking Buildbucket for the build status
[07:45:02.734] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:02.734] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:52:0) after 1m0s
[07:46:02.767] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:52:0)
[07:46:02.767] Timer tick, asking Buildbucket for the build status
[07:46:03.121] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:03.121] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:53:0) after 1m0s
[07:47:03.207] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:53:0)
[07:47:03.207] Timer tick, asking Buildbucket for the build status
[07:47:03.673] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:03.673] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:54:0) after 1m0s
[07:48:03.745] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:54:0)
[07:48:03.745] Timer tick, asking Buildbucket for the build status
[07:48:03.870] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:03.870] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:55:0) after 1m0s
[07:49:03.923] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:55:0)
[07:49:03.923] Timer tick, asking Buildbucket for the build status
[07:49:04.190] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:04.190] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:56:0) after 1m0s
[07:50:04.212] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:56:0)
[07:50:04.212] Timer tick, asking Buildbucket for the build status
[07:50:04.662] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:04.662] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:57:0) after 1m0s
[07:51:04.748] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:57:0)
[07:51:04.748] Timer tick, asking Buildbucket for the build status
[07:51:05.067] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:05.067] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:58:0) after 1m0s
[07:52:05.107] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:58:0)
[07:52:05.107] Timer tick, asking Buildbucket for the build status
[07:52:05.195] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:05.195] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:59:0) after 1m0s
[07:53:05.337] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:59:0)
[07:53:05.337] Timer tick, asking Buildbucket for the build status
[07:53:05.419] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:05.419] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:60:0) after 1m0s
[07:54:05.487] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:60:0)
[07:54:05.487] Timer tick, asking Buildbucket for the build status
[07:54:05.744] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:05.744] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:61:0) after 1m0s
[07:55:05.829] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:61:0)
[07:55:05.829] Timer tick, asking Buildbucket for the build status
[07:55:06.031] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:06.031] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:62:0) after 1m0s
[07:56:06.085] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:62:0)
[07:56:06.085] Timer tick, asking Buildbucket for the build status
[07:56:06.244] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:06.244] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:63:0) after 1m0s
[07:57:06.359] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:63:0)
[07:57:06.359] Timer tick, asking Buildbucket for the build status
[07:57:08.158] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:08.158] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:64:0) after 1m0s
[07:58:08.242] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:64:0)
[07:58:08.242] Timer tick, asking Buildbucket for the build status
[07:58:08.330] Build 8853800849473855456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:08.330] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019230911451016256:65:0) after 1m0s
[07:58:22.582] Received PubSub notification, asking Buildbucket for the build status
[07:58:22.734] Build 8853800849473855456: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[07:58:22.734] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614758302106801",
"created_by": "project:chromium",
"created_ts": "1614754549488548",
"failure_reason": "BUILD_FAILURE",
"id": "8853800849473855456",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0599cb17daae838b0ee05943628d5d0998d39c8e\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**content_browsertests** failed because of:\\n\\n- All/DumpAccessibilityEventsTest.AccessibilityEventsAriaDropeffectChanged/uia\"}, \"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\": \"0599cb17daae838b0ee05943628d5d0998d39c8e\", \"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-9-nth8\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17604, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-3\", \"got_angle_revision\": \"1de8b5a697e731c03083bda16b2df67c1d7a4c47\", \"got_dawn_revision\": \"f9d75ead9e2de4258fd7120d7d6b6c7f9b211b84\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"0599cb17daae838b0ee05943628d5d0998d39c8e\", \"got_revision_cp\": \"refs/heads/master@{#859279}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"106e01583a3ac2fb950777390dde2dcc31a8f161\", \"got_v8_revision\": \"d46e09ffb7fdfb9724c0b0e24f12ad5ec9628c41\", \"got_v8_revision_cp\": \"refs/heads/9.1.28@{#1}\", \"got_webrtc_revision\": \"07d83c8a9af02a0afc473503f75f150f23e3e0ff\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33366}\", \"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\": \"0599cb17daae838b0ee05943628d5d0998d39c8e\", \"swarm_hashes_refs/heads/master(at){#859279}_without_patch\": {\"absl_hardening_tests\": \"b2894e1cffc6a008150925386fecd272970dd921\", \"accessibility_unittests\": \"4e74d2453881b97401a7cf763b5d13d2668fee10\", \"angle_unittests\": \"e3adbb9c8de21465ea6666096639f1374724a185\", \"app_shell_unittests\": \"ea709511b7fb4c6f14b61d9c0b015ecf51d29f99\", \"aura_unittests\": \"e3a70dc6a59410698c9025d8a8d4d061e4710e62\", \"base_unittests\": \"0be9e60852fd370fd702a99482420387be8ee5c0\", \"base_util_unittests\": \"9858975545a4fa0521ee71f503b1a99b69b786a2\", \"blink_common_unittests\": \"6c7e409293762a9fdb6e34bb15a29b068c2d6d2d\", \"blink_fuzzer_unittests\": \"16761e23dc15997b71f2e91e872a1065a25ced16\", \"blink_heap_unittests\": \"f9bdf66fb7bf19fd50f5cf018fdadbb5062d66a9\", \"blink_platform_unittests\": \"88d4d841c37dd4c2f3c509f748b4aafb44f689e2\", \"blink_unittests\": \"ca9bb23e124a0ebfb970f0f9c5588dd5b35ea779\", \"boringssl_crypto_tests\": \"2851b613dae0b47fb872bdab8537236ce5099f2e\", \"boringssl_ssl_tests\": \"705ad431f02ee407e62883c61ab8f3af7790c86b\", \"browser_switcher_bho_unittests\": \"05ba4cde081c6b3faf7d0ef6dd09d206aca8c48c\", \"browser_tests\": \"f2d22af505c9f66c3feda78eebff894b0bfa4ef2\", \"capture_unittests\": \"896c062b02f8dbebfe7b888af61429d26907a368\", \"cast_unittests\": \"94f9ae3bb911dbd68caaf15b6e9049c543ce110b\", \"cc_unittests\": \"632a516af9499d6ebe09463699840171a9234d20\", \"chrome_app_unittests\": \"d6f2f6ea2b654270b5f1fc08be68589262d287b6\", \"chrome_cleaner_unittests\": \"58bec53c7961df0a118a0bddcde7d7e4d988b05f\", \"chrome_elf_unittests\": \"6568c0a4143c0adb2332f9a532857361a61d0613\", \"chromedriver_unittests\": \"4e18255332e43d69bd068d514d01d025e4f49369\", \"color_unittests\": \"af48da935a4ca43cd488f693f53018184fa195e7\", \"components_browsertests\": \"0928786bd0a119a4fb42330239ce9a7e1d6758f1\", \"components_unittests\": \"25524f6eb9f7a2e2871609641d5e4db73d3bc324\", \"compositor_unittests\": \"cf550b4c2c33f03d024ae17fd939b6a5af93a80f\", \"content_browsertests\": \"f3439f30c46ea9de575eaf573e93156e77533eb2\", \"content_unittests\": \"f011d8dd39c348623870ecc1f9bb9de8c5b0895d\", \"courgette_unittests\": \"bdd3197d83eb70829d91784fd6556cc0357369df\", \"crashpad_tests\": \"ed95cc10f520d9c81d1292ce32c7e46d1ed3ad29\", \"cronet_tests\": \"e2a44b2ab91cf1630217e9fb3c7ade3fa42305a5\", \"cronet_unittests\": \"75b1d2e6b4fb4b4b5e631133679b887297f35b87\", \"crypto_unittests\": \"01f8dfddef82134720e9021c53207014358f4995\", \"delayloads_unittests\": \"56ff844c86af3211af6871fa7cd5ca7061f51bb6\", \"device_unittests\": \"af9431c5f04e1e9c5a58f8fcfa486b015ba9c20f\", \"display_unittests\": \"d8bc560d5f6ece19b249502fbcbc658510b629b4\", \"elevation_service_unittests\": \"cb198b6103a6ae84e36d81f11572bc42dffbb87f\", \"events_unittests\": \"b1df2b763d184f3d92ffc9aa56b4aac47906b27a\", \"extensions_browsertests\": \"321f4ffcfc63bb87225153ccc7153512aca1021b\", \"extensions_unittests\": \"139bf2c4766d1aadfb4317b367296f1789b82475\", \"filesystem_service_unittests\": \"83f588d96504f40f83cad576f153829fc9429208\", \"gcm_unit_tests\": \"5e99689cc38ec3a93a026a543b43f41ec0d663f5\", \"gcp_unittests\": \"8e857c9dffcf5f269548b386c320e12029bd6e4b\", \"gfx_unittests\": \"6532e2caa8635425672c792f7a34f12dd180cfff\", \"gin_unittests\": \"cdc1e624618a9fea7c34cfbdc4c879daf410cce8\", \"google_apis_unittests\": \"9dac9227db19d027102826b255db04c377acdaad\", \"gpu_unittests\": \"b5df6bee0b15be08ba8bbc2694a10d88f752149a\", \"gwp_asan_unittests\": \"e5cb310e15bd585ba87c56a144d575eed812b2b4\", \"headless_browsertests\": \"67fa23e3d43645bdbd7f8c55812516b7c20d1d9d\", \"headless_unittests\": \"8847cd447f612fa7f7c48ed319d59f6c31631c0d\", \"install_static_unittests\": \"fb456acca183aa7b6b7f282e73e022a7e6751038\", \"installer_util_unittests\": \"13b84f6b0e410daf8a7689b70b67666a5c603138\", \"interactive_ui_tests\": \"cca2b319c53711ad87b36a24e03de8201f6c1c16\", \"ipc_tests\": \"e62e3c542dcef2a1cedc3b171edadd5adefb008d\", \"jingle_unittests\": \"50431aeef24ac2a75d47670da100e06d8c0f77d4\", \"latency_unittests\": \"ab58cd83d338dff2a74c8401ddd1684491edbec1\", \"libjingle_xmpp_unittests\": \"facd7315c50b0f84173e22eef55be6c39bfd628d\", \"liburlpattern_unittests\": \"96668289bc628b9d3166ffe6c70e3f6456c1a2a5\", \"media_blink_unittests\": \"1cc9d5f82c8594078cd6f1ffe3792d356d4ed4ee\", \"media_unittests\": \"8ecf239ad669ce4c680b1498ad010030cb045d1a\", \"message_center_unittests\": \"8e04cb4c19f166624f7289305c6aac221de7996f\", \"midi_unittests\": \"85797e745444a7af3827f0b43d64095f70340cd2\", \"mojo_core_unittests\": \"fa1be3f1ed56cf724355596a8226c4f38c1e2913\", \"mojo_unittests\": \"15dcaf2ff80644d646f0de33668172902e791e77\", \"nacl_loader_unittests\": \"bf033a8b6b37537ea1c8fb5fc668f1ff68a19823\", \"native_theme_unittests\": \"2c3cb0a10770a66a2287cf2efd0a4c7806105da7\", \"net_unittests\": \"35018a46427ecc9ef630e7dd75b423e0f2305c0b\", \"notification_helper_unittests\": \"7fd162656d5686e37b33fd29e88115dfc1422223\", \"pdf_unittests\": \"5165ff08ede9a2aa327473c92e90ea529b86096a\", \"perfetto_unittests\": \"9236a26b4b2c4dd32e800217820ffcfbe13ca11b\", \"ppapi_unittests\": \"0c417ff741e641b5fc7ab33cf97ff2d09b316de3\", \"printing_unittests\": \"a318da4db309495b72f0933b9835cd4c79964842\", \"remoting_unittests\": \"75ee69edafd8a3c8ca34770b8480b6037acbe1fa\", \"sbox_integration_tests\": \"0851318f548696ccea635e86e6e5c9a3b6131fbb\", \"sbox_unittests\": \"4ae7d00e34af870641871e2ec5f7e784141574f1\", \"sbox_validation_tests\": \"d5e9ca3db8a26748141ccf473e5cd648286afe16\", \"service_manager_unittests\": \"c48a7a7e916d81b28f2933bc06eab07ad56963ab\", \"services_unittests\": \"9a6dff4cc1873d968c1cee361c7043beff676e0d\", \"setup_unittests\": \"bbcb18ec54b04013690426c4de0bad603ac5ea47\", \"shell_dialogs_unittests\": \"1a6249d298d74a0da59270c5deb1dbd6dd8964d3\", \"skia_unittests\": \"20262770bdc1b79d06d2cc55b4b2e1b98eee3a34\", \"snapshot_unittests\": \"50348153191d932f94ff6b88bad94104f6b4e267\", \"sql_unittests\": \"844a0a845327d52f119db3a5a9dd3c3c6bd0af26\", \"storage_unittests\": \"0ec556717ce1b222a9f449258c0ab507da98ac70\", \"sync_integration_tests\": \"2bffd85e09867628738319b8a1ae56fa68ad127b\", \"traffic_annotation_auditor_unittests\": \"131356979932340e337b3012afc0a1d01f97f03f\", \"ui_base_unittests\": \"457c07890838e1a134d19fb1d6657ad1aa20afea\", \"ui_touch_selection_unittests\": \"201fd8b14c911b54c9b498252a4a0c5d28896a75\", \"unit_tests\": \"838246b2859e6c2be61df2fc428c24f8a8bfa34a\", \"updater_tests\": \"22d45957c893355a2313c64be7dff38c92d3f48b\", \"url_unittests\": \"e4c3ba37d7b7bbfc3d3edd9ff8b66de2b402c7ea\", \"views_unittests\": \"90bacf682d962999a39acdf721bdcde84c9e316e\", \"viz_unittests\": \"e10e992b4c8614e3932087d83278298e4789b873\", \"vr_common_unittests\": \"83650b30427dd4e86e09e6c8a3871740a272ccb5\", \"vr_pixeltests\": \"f2e14f7499cc223bd4397f622058113195c67606\", \"weblayer_browsertests\": \"8b0aa7b9baf542360d7c138df2602b4d6db633b1\", \"weblayer_unittests\": \"7912363624efb2a709001cc5b0b7ad3f5e45bc51\", \"wm_unittests\": \"eb310471a41ebbed9d3fa591bddace5c781be3df\", \"wtf_unittests\": \"af48c0b4c4b6c6dc82a0f94324659c4743304b1f\", \"zlib_unittests\": \"27f60af9eb791fb087cfd5ba615657403533714f\", \"zucchini_unittests\": \"f8c7c89906c094a925d83ef2e01820bbd7f1ace8\"}}, \"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-9-nth8\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"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\": [\"5725-1a0d85e\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**content_browsertests** failed because of:\\n\\n- All/DumpAccessibilityEventsTest.AccessibilityEventsAriaDropeffectChanged/uia\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1614754552698956",
"status": "COMPLETED",
"status_changed_ts": "1614754553029090",
"tags": [
"build_address:luci.chromium.ci/win-asan/17604",
"builder:win-asan",
"buildset:commit/git/0599cb17daae838b0ee05943628d5d0998d39c8e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0599cb17daae838b0ee05943628d5d0998d39c8e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019230911451016256",
"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/8853800849473855456/+/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:520fb0996ee97110",
"user_agent:luci-scheduler"
],
"updated_ts": "1614758302107202",
"url": "https://ci.chromium.org/b/8853800849473855456",
"utcnow_ts": "1614758302723034"
}
}
[07:58:22.734] Invocation finished in 1h2m34.515165034s with status FAILED