[07:37:45.310] New invocation is queued and will start shortly
[07:37:46.629] Starting the invocation (attempt 1)
[07:37:46.666] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:37:46.666] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:37:46.666] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9024301702225033488",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"0513c111465c8803786f4fbdf3224a8bbb27e97b\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9024301702225033488",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/0513c111465c8803786f4fbdf3224a8bbb27e97b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0513c111465c8803786f4fbdf3224a8bbb27e97b",
"gitiles_ref:refs/heads/master"
]
}
[07:37:47.420] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1609918666782540",
"id": "8858871640018485456",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0513c111465c8803786f4fbdf3224a8bbb27e97b\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1609918667195301",
"tags": [
"build_address:luci.chromium.ci/win-asan/16650",
"builder:win-asan",
"buildset:commit/git/0513c111465c8803786f4fbdf3224a8bbb27e97b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0513c111465c8803786f4fbdf3224a8bbb27e97b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024301702225033488",
"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/8858871640018485456/+/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": "1609918667195417",
"url": "https://ci.chromium.org/b/8858871640018485456",
"utcnow_ts": "1609918667413406"
}
}
[07:37:47.420] Task URL: https://ci.chromium.org/b/8858871640018485456
[07:37:47.420] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:2:0) after 1m0s
[07:37:51.288] Received PubSub notification, asking Buildbucket for the build status
[07:37:51.494] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:47.438] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:2:0)
[07:38:47.438] Timer tick, asking Buildbucket for the build status
[07:38:47.509] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:47.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:4:0) after 1m0s
[07:39:47.552] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:4:0)
[07:39:47.552] Timer tick, asking Buildbucket for the build status
[07:39:47.689] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:47.689] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:5:0) after 1m0s
[07:40:47.733] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:5:0)
[07:40:47.733] Timer tick, asking Buildbucket for the build status
[07:40:47.824] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:47.824] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:6:0) after 1m0s
[07:41:47.859] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:6:0)
[07:41:47.859] Timer tick, asking Buildbucket for the build status
[07:41:47.980] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:47.980] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:7:0) after 1m0s
[07:42:48.097] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:7:0)
[07:42:48.097] Timer tick, asking Buildbucket for the build status
[07:42:48.236] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:48.236] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:8:0) after 1m0s
[07:43:48.234] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:8:0)
[07:43:48.234] Timer tick, asking Buildbucket for the build status
[07:43:48.363] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:48.363] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:9:0) after 1m0s
[07:44:48.450] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:9:0)
[07:44:48.450] Timer tick, asking Buildbucket for the build status
[07:44:48.683] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:48.683] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:10:0) after 1m0s
[07:45:48.746] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:10:0)
[07:45:48.746] Timer tick, asking Buildbucket for the build status
[07:45:48.805] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:48.805] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:11:0) after 1m0s
[07:46:48.910] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:11:0)
[07:46:48.910] Timer tick, asking Buildbucket for the build status
[07:46:49.121] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:49.121] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:12:0) after 1m0s
[07:47:49.203] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:12:0)
[07:47:49.203] Timer tick, asking Buildbucket for the build status
[07:47:49.312] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:49.312] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:13:0) after 1m0s
[07:48:49.499] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:13:0)
[07:48:49.499] Timer tick, asking Buildbucket for the build status
[07:48:49.568] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:49.568] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:14:0) after 1m0s
[07:49:49.885] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:14:0)
[07:49:49.885] Timer tick, asking Buildbucket for the build status
[07:49:49.959] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:49.959] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:15:0) after 1m0s
[07:50:50.115] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:15:0)
[07:50:50.115] Timer tick, asking Buildbucket for the build status
[07:50:50.188] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:50.188] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:16:0) after 1m0s
[07:51:50.385] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:16:0)
[07:51:50.385] Timer tick, asking Buildbucket for the build status
[07:51:50.627] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:50.627] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:17:0) after 1m0s
[07:52:51.100] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:17:0)
[07:52:51.100] Timer tick, asking Buildbucket for the build status
[07:52:51.271] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:51.271] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:18:0) after 1m0s
[07:53:51.394] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:18:0)
[07:53:51.394] Timer tick, asking Buildbucket for the build status
[07:53:51.519] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:51.519] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:19:0) after 1m0s
[07:54:51.628] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:19:0)
[07:54:51.628] Timer tick, asking Buildbucket for the build status
[07:54:51.694] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:51.694] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:20:0) after 1m0s
[07:55:51.802] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:20:0)
[07:55:51.802] Timer tick, asking Buildbucket for the build status
[07:55:51.857] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:51.857] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:21:0) after 1m0s
[07:56:51.986] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:21:0)
[07:56:51.986] Timer tick, asking Buildbucket for the build status
[07:56:52.192] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:52.192] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:22:0) after 1m0s
[07:57:52.391] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:22:0)
[07:57:52.391] Timer tick, asking Buildbucket for the build status
[07:57:52.548] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:52.548] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:23:0) after 1m0s
[07:58:52.920] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:23:0)
[07:58:52.920] Timer tick, asking Buildbucket for the build status
[07:58:53.291] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:53.291] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:24:0) after 1m0s
[07:59:53.390] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:24:0)
[07:59:53.390] Timer tick, asking Buildbucket for the build status
[07:59:53.779] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:53.779] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:25:0) after 1m0s
[08:00:53.949] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:25:0)
[08:00:53.949] Timer tick, asking Buildbucket for the build status
[08:00:54.299] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:54.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:26:0) after 1m0s
[08:01:54.366] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:26:0)
[08:01:54.366] Timer tick, asking Buildbucket for the build status
[08:01:54.477] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:54.477] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:27:0) after 1m0s
[08:02:54.612] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:27:0)
[08:02:54.612] Timer tick, asking Buildbucket for the build status
[08:02:54.919] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:54.919] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:28:0) after 1m0s
[08:03:54.992] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:28:0)
[08:03:54.992] Timer tick, asking Buildbucket for the build status
[08:03:55.128] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:55.128] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:29:0) after 1m0s
[08:04:56.119] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:29:0)
[08:04:56.119] Timer tick, asking Buildbucket for the build status
[08:04:56.255] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:56.255] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:30:0) after 1m0s
[08:05:56.512] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:30:0)
[08:05:56.512] Timer tick, asking Buildbucket for the build status
[08:05:56.966] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:56.966] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:31:0) after 1m0s
[08:06:56.989] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:31:0)
[08:06:56.989] Timer tick, asking Buildbucket for the build status
[08:06:57.595] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:57.595] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:32:0) after 1m0s
[08:07:57.624] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:32:0)
[08:07:57.624] Timer tick, asking Buildbucket for the build status
[08:07:57.830] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:57.830] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:33:0) after 1m0s
[08:08:57.889] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:33:0)
[08:08:57.889] Timer tick, asking Buildbucket for the build status
[08:08:58.047] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:58.047] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:34:0) after 1m0s
[08:09:58.166] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:34:0)
[08:09:58.166] Timer tick, asking Buildbucket for the build status
[08:09:58.299] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:58.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:35:0) after 1m0s
[08:10:58.314] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:35:0)
[08:10:58.314] Timer tick, asking Buildbucket for the build status
[08:10:58.495] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:58.495] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:36:0) after 1m0s
[08:11:58.582] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:36:0)
[08:11:58.582] Timer tick, asking Buildbucket for the build status
[08:11:58.718] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:58.718] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:37:0) after 1m0s
[08:12:58.702] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:37:0)
[08:12:58.702] Timer tick, asking Buildbucket for the build status
[08:12:58.859] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:58.859] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:38:0) after 1m0s
[08:13:59.189] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:38:0)
[08:13:59.189] Timer tick, asking Buildbucket for the build status
[08:13:59.423] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:59.423] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:39:0) after 1m0s
[08:14:59.458] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:39:0)
[08:14:59.458] Timer tick, asking Buildbucket for the build status
[08:14:59.539] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:59.539] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:40:0) after 1m0s
[08:15:59.683] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:40:0)
[08:15:59.683] Timer tick, asking Buildbucket for the build status
[08:15:59.793] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:59.793] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:41:0) after 1m0s
[08:16:59.771] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:41:0)
[08:16:59.771] Timer tick, asking Buildbucket for the build status
[08:17:00.147] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:00.147] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:42:0) after 1m0s
[08:18:00.193] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:42:0)
[08:18:00.193] Timer tick, asking Buildbucket for the build status
[08:18:00.323] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:00.323] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:43:0) after 1m0s
[08:19:00.344] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:43:0)
[08:19:00.344] Timer tick, asking Buildbucket for the build status
[08:19:00.528] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:00.528] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:44:0) after 1m0s
[08:20:00.544] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:44:0)
[08:20:00.544] Timer tick, asking Buildbucket for the build status
[08:20:00.699] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:00.699] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:45:0) after 1m0s
[08:21:00.767] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:45:0)
[08:21:00.767] Timer tick, asking Buildbucket for the build status
[08:21:00.924] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:00.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:46:0) after 1m0s
[08:22:01.191] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:46:0)
[08:22:01.191] Timer tick, asking Buildbucket for the build status
[08:22:01.683] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:01.683] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:47:0) after 1m0s
[08:23:01.716] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:47:0)
[08:23:01.716] Timer tick, asking Buildbucket for the build status
[08:23:02.249] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:02.249] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:48:0) after 1m0s
[08:24:02.425] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:48:0)
[08:24:02.425] Timer tick, asking Buildbucket for the build status
[08:24:02.538] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:02.538] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:49:0) after 1m0s
[08:25:02.677] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:49:0)
[08:25:02.677] Timer tick, asking Buildbucket for the build status
[08:25:02.802] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:02.802] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:50:0) after 1m0s
[08:26:02.811] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:50:0)
[08:26:02.811] Timer tick, asking Buildbucket for the build status
[08:26:03.002] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:03.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:51:0) after 1m0s
[08:27:03.484] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:51:0)
[08:27:03.484] Timer tick, asking Buildbucket for the build status
[08:27:03.627] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:03.627] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:52:0) after 1m0s
[08:28:03.849] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:52:0)
[08:28:03.849] Timer tick, asking Buildbucket for the build status
[08:28:04.047] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:04.047] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:53:0) after 1m0s
[08:29:04.327] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:53:0)
[08:29:04.327] Timer tick, asking Buildbucket for the build status
[08:29:04.433] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:04.433] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:54:0) after 1m0s
[08:30:04.595] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:54:0)
[08:30:04.595] Timer tick, asking Buildbucket for the build status
[08:30:04.884] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:04.884] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:55:0) after 1m0s
[08:31:04.996] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:55:0)
[08:31:04.996] Timer tick, asking Buildbucket for the build status
[08:31:05.120] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:05.120] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:56:0) after 1m0s
[08:32:05.425] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:56:0)
[08:32:05.425] Timer tick, asking Buildbucket for the build status
[08:32:05.596] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:05.596] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:57:0) after 1m0s
[08:33:05.686] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:57:0)
[08:33:05.686] Timer tick, asking Buildbucket for the build status
[08:33:05.819] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:05.819] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:58:0) after 1m0s
[08:34:05.891] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:58:0)
[08:34:05.891] Timer tick, asking Buildbucket for the build status
[08:34:05.971] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:05.971] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:59:0) after 1m0s
[08:35:06.018] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:59:0)
[08:35:06.018] Timer tick, asking Buildbucket for the build status
[08:35:06.209] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:06.209] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:60:0) after 1m0s
[08:36:06.256] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:60:0)
[08:36:06.256] Timer tick, asking Buildbucket for the build status
[08:36:06.401] Build 8858871640018485456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:06.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024301702225033488:61:0) after 1m0s
[08:36:31.218] Received PubSub notification, asking Buildbucket for the build status
[08:36:31.864] Build 8858871640018485456: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[08:36:31.864] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1609922190207999",
"created_by": "project:chromium",
"created_ts": "1609918666782540",
"failure_reason": "BUILD_FAILURE",
"id": "8858871640018485456",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0513c111465c8803786f4fbdf3224a8bbb27e97b\"}}",
"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\\n\\n- All/StartupTracingTest.TestEnableTracing/7\\n\\n- All/StartupTracingTest.TestEnableTracing/6\\n\\n- All/StartupTracingTest.TestEnableTracing/8\"}, \"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\": \"0513c111465c8803786f4fbdf3224a8bbb27e97b\", \"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-2-pxlj\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16650, \"clang_revision\": \"llvmorg-12-init-15676-g5c951623-1\", \"got_angle_revision\": \"b590fd1b9a590909ff184f44c8bf844caecc8d14\", \"got_dawn_revision\": \"a84acc4fcca80d80c379e3b02d8dc0f7b102ba9d\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"0513c111465c8803786f4fbdf3224a8bbb27e97b\", \"got_revision_cp\": \"refs/heads/master@{#840517}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"b9e179f1f239bd1da8fffbd997a41095345d1f6a\", \"got_v8_revision\": \"8bf25fa0cda754dd0f8e0f8b7c9f8854eabfe72d\", \"got_v8_revision_cp\": \"refs/heads/8.9.213@{#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\": \"0513c111465c8803786f4fbdf3224a8bbb27e97b\", \"swarm_hashes_refs/heads/master(at){#840517}_without_patch\": {\"absl_hardening_tests\": \"3619ef4facb109dfd951d838ac3e1b0258d403d5\", \"accessibility_unittests\": \"38b65bb840539c028236c49259e20a1340e1c508\", \"angle_unittests\": \"9ac8cf7a72af741684477941678042fc95d18087\", \"app_shell_unittests\": \"408086bbe7afa45ff15438ea9e325530a29661de\", \"aura_unittests\": \"18101d85cff84d5a8ee6df99fe8eff21279641ee\", \"base_unittests\": \"799d9ee80da31d843bb00b6961d3781e64a0c1d7\", \"base_util_unittests\": \"92dcb6077b410679a3eecfced202e269fbf2fdac\", \"blink_common_unittests\": \"6f1afe1eca843559e2ea16a3b8349284151efe4c\", \"blink_fuzzer_unittests\": \"860d9b537152c7fb112d9419cef17a96331468e5\", \"blink_heap_unittests\": \"87b8c5e96da75635ea9ed8e0b5543d7c404406fd\", \"blink_platform_unittests\": \"0dfb06de3fe9999fe10a08b848413ffa0138ccbf\", \"blink_unittests\": \"acf5189a4263a94bed23084b9f1d125fe82d4f85\", \"boringssl_crypto_tests\": \"b8a17d0d3dd7c5abeb86d6f5510a737c5b74056e\", \"boringssl_ssl_tests\": \"f053e0c96e683ddf41e94fb6a65da79f6d77b655\", \"browser_switcher_bho_unittests\": \"90f11e461d5eabf0800a1e5173e5f2c63cb63d46\", \"browser_tests\": \"a4be09a1dbd4750a977b2e6778f207176aaf0ff3\", \"capture_unittests\": \"db84340f7e9e6946f33ac622ea05a30baae00673\", \"cast_unittests\": \"489dbde98e6c39450d742fab58b1ee2455624222\", \"cc_unittests\": \"e57d6b87ebaed4638efc97de0b40aac2af99eb0c\", \"chrome_app_unittests\": \"ebd1c12137ae1be5cac6e8983c16aab63d558a4e\", \"chrome_cleaner_unittests\": \"db22db509627e738f67284168b1bdab039de2629\", \"chrome_elf_unittests\": \"cd8a76e3a26923eb6ca37e9cce445d6aa6a66087\", \"chromedriver_unittests\": \"c6501714611737192097783dcb1c7600f15a73ca\", \"components_browsertests\": \"c7ae544b4c1e63c0664bb9ebd2de3f457e82fd0a\", \"components_unittests\": \"be670cfd48ea15b27065b8e73d61a5d22c9019d6\", \"compositor_unittests\": \"c974b40d2f33f104c4449dd592f004877197bd2e\", \"content_browsertests\": \"316fb1277bd14de283cf3316af9dafc3adf9c7b0\", \"content_unittests\": \"c2e3a3fb365a8ef37c456c54d902713bead8766b\", \"courgette_unittests\": \"bd40b14374057e10e0644c3256c0439f7ac0fb8d\", \"crashpad_tests\": \"08197af72fa205832c6c5ad7841164b36262fc44\", \"cronet_tests\": \"a9ef068d437e8d52b7c555c5cf28f212031550b0\", \"cronet_unittests\": \"4b8cfe062215c42a5aacbcde800c2f5b4d7592aa\", \"crypto_unittests\": \"9040a2cd330e80aed96a2267ad06b577926bc848\", \"delayloads_unittests\": \"8208b6cfd5c7c0a340b10f4070eea3499588343b\", \"device_unittests\": \"7207023b05267a900bb7fe3bc81a5f0fb1cb2ca2\", \"display_unittests\": \"de95003735e8202bb00c69dbf7daa6828bac7c40\", \"elevation_service_unittests\": \"2d603431b451cd4a5175adabb95c5cc7d638e1c2\", \"events_unittests\": \"cca91bfd2769bc37979dff8740ef927b3d6b4d82\", \"extensions_browsertests\": \"58033c512b179f3afb514afdb5ba8fa89d60dd28\", \"extensions_unittests\": \"5829adc547ed8f5899ac23f8af3ba8674cc2c10b\", \"filesystem_service_unittests\": \"e176117e64f93dc0a510cab12cd1c8533bb5a55f\", \"gcm_unit_tests\": \"c2b401ff99c07b13c2abf978dfcbe9eb46093f44\", \"gcp_unittests\": \"53af9eb8f24c9ed0d1e678cfe19435c8badf3a17\", \"gfx_unittests\": \"0328f03ca8458381199286d8ccd2e0e1a9acb932\", \"gin_unittests\": \"70279b4711f4abe044c7419e8bfe499f7e400970\", \"google_apis_unittests\": \"97a4507c1a7b807d9bd10de543ba821775afd6f2\", \"gpu_unittests\": \"fec9945361ceca7b4f982a0632ece1093ce39f30\", \"gwp_asan_unittests\": \"78b1d7d1726c5a7d381c2b85cad0689c81cf9df2\", \"headless_browsertests\": \"86599f9bd2d88dbf9d6babef5bf0f8d25c0dd13d\", \"headless_unittests\": \"4ab9b37f646dfe574297265a79eaa38f5cd00892\", \"install_static_unittests\": \"00bee8c194ac8be06e4302deda75dca47b469a8b\", \"installer_util_unittests\": \"d29e47c5ae97f33d71f35f82d726cd6e0a9d9829\", \"interactive_ui_tests\": \"13366232b19167114fe673505d0894398a1b7f2f\", \"ipc_tests\": \"938ea97f64f56ad31b0805015876b32a06867ae9\", \"jingle_unittests\": \"9409ea5f7a096b810448816ff8d6e85a46ad2480\", \"latency_unittests\": \"921bda8b1ffdf1c2e6d90630a37d78f6e5550314\", \"libjingle_xmpp_unittests\": \"b0b25c48ef69be7f587b6c9bd5b707baa56d468b\", \"liburlpattern_unittests\": \"578a045624676b7e7e8f995714e6133b9828be61\", \"media_blink_unittests\": \"83454891b22510424c8aaf1782ac26ee5d3cc7f8\", \"media_unittests\": \"6dd80de0f8a2b453f0e05d8abec9a2e6218354f0\", \"message_center_unittests\": \"0384bd66fbd52f5d85accbaa7285da4da01e015b\", \"midi_unittests\": \"c3432b502ab8da5370696a7a60ac2dc74fbaa0fa\", \"mojo_core_unittests\": \"8bd16ed9aac740129101138402e00a44c97654a0\", \"mojo_unittests\": \"8d2a0fcb095f53da8c8a10fa57bf15dd87a65465\", \"nacl_loader_unittests\": \"9f909fb6093b1536af0de070a17f5e9dc609ae42\", \"native_theme_unittests\": \"6c038950b74fb89b534c20d457063cf7c2334a15\", \"net_unittests\": \"7a6fd3e837e753adb708a6c6bb831d49a73490b2\", \"notification_helper_unittests\": \"d0283668441fb31c2c0b54666ef90545672ccbda\", \"pdf_unittests\": \"8256e7cf37afc6f1a0b97ed189f47d82e0a9197e\", \"perfetto_unittests\": \"10b9db2156700818a88fc387c85e8565517f356d\", \"ppapi_unittests\": \"5e0c0096a1437e9694bc500a134264f19d20fa4d\", \"printing_unittests\": \"fd800e5e2a57d5e37d64283fb81eec9d2b612f0c\", \"remoting_unittests\": \"5ea2fb957cd93e3aa0e45283d434132bf3ac7eee\", \"sbox_integration_tests\": \"5d5a8944f0e488422c37335ec61073813a6c10c3\", \"sbox_unittests\": \"b0686360878859fbf452f012cbcccbe9f146ad22\", \"sbox_validation_tests\": \"38354a81400ee4ab32c757ddc30df687dacf4df5\", \"service_manager_unittests\": \"dfcd00ff0529974b072d89cc2fc8987286a95c9c\", \"services_unittests\": \"2af2e862daba76572d1893e0967ed1accf13a914\", \"setup_unittests\": \"c3d26bd55d91638800193b3d44fb110299c633eb\", \"shell_dialogs_unittests\": \"d7f511293368b74f7815d1fb9a9b06aec1cb7c9d\", \"skia_unittests\": \"1b82e5678167c12cc0524db52152b1dac291768f\", \"snapshot_unittests\": \"89523dfb884487e7b52bc5562a5f02ede37e667a\", \"sql_unittests\": \"a5771b73fbb9bd818aebb068a61d05edc1261fa1\", \"storage_unittests\": \"96c60d8347410b13eb57bd1196c3ed0e894f5052\", \"sync_integration_tests\": \"1625eab558dd8ad315e1528c965a801122fa0b61\", \"traffic_annotation_auditor_unittests\": \"d135672acaa57932adcd6d605b97182ff55c7001\", \"ui_base_unittests\": \"dd62468a3a5855dbea846aca73cc1439fd1b8e0f\", \"ui_touch_selection_unittests\": \"3ee5c0e5930accc7a7863d52dc86888966369033\", \"unit_tests\": \"e9155c25ef7f3d5d5e352bc5ebd975db09257c4c\", \"updater_tests\": \"aacfc4a3e78c70302820b27bbf4e86d08240379e\", \"url_unittests\": \"7233c7fa9076c00b5a9654748675dcc2e3139df0\", \"views_unittests\": \"e11fc2b2003d5e19cb6fb027fc8d71990dee7e74\", \"viz_unittests\": \"23d9924d58abce1179272e13a32110b0e1ed16e1\", \"vr_common_unittests\": \"9c3ef93bf9fa93d348897ceb355ab98dedb23218\", \"vr_pixeltests\": \"912c51f3c7e4115fda214dd95a83b6a25c81c57b\", \"weblayer_browsertests\": \"68cf69cbe2c62dcabdd289a36085c90743c6ee51\", \"weblayer_unittests\": \"10b7ecbaa13c75e62107dd71e59314377829f0f3\", \"wm_unittests\": \"956b5f5a8f4ca5bc448ac931cb880a933cb17c57\", \"wtf_unittests\": \"38a6268b61be48732b405fe0ce2b01d87113d986\", \"zlib_unittests\": \"edcf67a8c9c818f57fef9e3db8dad88a48273a46\", \"zucchini_unittests\": \"eb84c50a548da171675a928df5c27fc4b9d08a07\"}}, \"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-2-pxlj\"], \"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**content_browsertests** failed because of:\\n\\n- All/DumpAccessibilityEventsTest.AccessibilityEventsAriaDropeffectChanged/uia\\n\\n- All/StartupTracingTest.TestEnableTracing/7\\n\\n- All/StartupTracingTest.TestEnableTracing/6\\n\\n- All/StartupTracingTest.TestEnableTracing/8\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1609918670459075",
"status": "COMPLETED",
"status_changed_ts": "1609918671053179",
"tags": [
"build_address:luci.chromium.ci/win-asan/16650",
"builder:win-asan",
"buildset:commit/git/0513c111465c8803786f4fbdf3224a8bbb27e97b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0513c111465c8803786f4fbdf3224a8bbb27e97b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024301702225033488",
"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/8858871640018485456/+/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:50ef72e348873410",
"user_agent:luci-scheduler"
],
"updated_ts": "1609922190912356",
"url": "https://ci.chromium.org/b/8858871640018485456",
"utcnow_ts": "1609922191831207"
}
}
[08:36:31.864] Invocation finished in 58m46.569890627s with status FAILED