[09:58:35.108] New invocation is queued and will start shortly
[09:58:36.251] Starting the invocation (attempt 1)
[09:58:36.288] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:58:36.290] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:58:36.290] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9026285975239096560",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"d04319f4d5a3cd956e565fa9e9bee25dfba82de6\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9026285975239096560",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/d04319f4d5a3cd956e565fa9e9bee25dfba82de6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d04319f4d5a3cd956e565fa9e9bee25dfba82de6",
"gitiles_ref:refs/heads/master"
]
}
[09:58:37.037] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1608026316406015",
"id": "8860855913206439024",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d04319f4d5a3cd956e565fa9e9bee25dfba82de6\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1608026316880389",
"tags": [
"build_address:luci.chromium.ci/win-asan/16238",
"builder:win-asan",
"buildset:commit/git/d04319f4d5a3cd956e565fa9e9bee25dfba82de6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d04319f4d5a3cd956e565fa9e9bee25dfba82de6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026285975239096560",
"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/8860855913206439024/+/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": "1608026316880559",
"url": "https://ci.chromium.org/b/8860855913206439024",
"utcnow_ts": "1608026317029748"
}
}
[09:58:37.037] Task URL: https://ci.chromium.org/b/8860855913206439024
[09:58:37.037] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:2:0) after 1m0s
[09:58:38.610] Received PubSub notification, asking Buildbucket for the build status
[09:58:38.692] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:37.074] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:2:0)
[09:59:37.074] Timer tick, asking Buildbucket for the build status
[09:59:37.172] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:37.173] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:4:0) after 1m0s
[10:00:37.186] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:4:0)
[10:00:37.186] Timer tick, asking Buildbucket for the build status
[10:00:37.324] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:37.324] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:5:0) after 1m0s
[10:01:37.336] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:5:0)
[10:01:37.336] Timer tick, asking Buildbucket for the build status
[10:01:37.446] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:37.446] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:6:0) after 1m0s
[10:02:37.472] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:6:0)
[10:02:37.472] Timer tick, asking Buildbucket for the build status
[10:02:37.603] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:37.603] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:7:0) after 1m0s
[10:03:37.632] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:7:0)
[10:03:37.632] Timer tick, asking Buildbucket for the build status
[10:03:37.769] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:37.769] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:8:0) after 1m0s
[10:04:37.822] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:8:0)
[10:04:37.822] Timer tick, asking Buildbucket for the build status
[10:04:37.933] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:37.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:9:0) after 1m0s
[10:05:37.995] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:9:0)
[10:05:37.995] Timer tick, asking Buildbucket for the build status
[10:05:38.057] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:38.057] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:10:0) after 1m0s
[10:06:38.054] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:10:0)
[10:06:38.054] Timer tick, asking Buildbucket for the build status
[10:06:38.121] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:38.121] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:11:0) after 1m0s
[10:07:38.220] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:11:0)
[10:07:38.220] Timer tick, asking Buildbucket for the build status
[10:07:38.292] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:38.292] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:12:0) after 1m0s
[10:08:38.452] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:12:0)
[10:08:38.452] Timer tick, asking Buildbucket for the build status
[10:08:38.574] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:38.575] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:13:0) after 1m0s
[10:09:38.632] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:13:0)
[10:09:38.632] Timer tick, asking Buildbucket for the build status
[10:09:38.695] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:38.695] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:14:0) after 1m0s
[10:10:38.808] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:14:0)
[10:10:38.808] Timer tick, asking Buildbucket for the build status
[10:10:39.030] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:39.030] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:15:0) after 1m0s
[10:11:39.170] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:15:0)
[10:11:39.170] Timer tick, asking Buildbucket for the build status
[10:11:39.285] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:39.285] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:16:0) after 1m0s
[10:12:39.426] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:16:0)
[10:12:39.426] Timer tick, asking Buildbucket for the build status
[10:12:39.654] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:39.654] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:17:0) after 1m0s
[10:13:39.671] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:17:0)
[10:13:39.671] Timer tick, asking Buildbucket for the build status
[10:13:39.795] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:39.795] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:18:0) after 1m0s
[10:14:39.818] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:18:0)
[10:14:39.819] Timer tick, asking Buildbucket for the build status
[10:14:40.132] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:40.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:19:0) after 1m0s
[10:15:40.125] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:19:0)
[10:15:40.125] Timer tick, asking Buildbucket for the build status
[10:15:40.264] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:40.264] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:20:0) after 1m0s
[10:16:40.284] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:20:0)
[10:16:40.284] Timer tick, asking Buildbucket for the build status
[10:16:40.696] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:40.696] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:21:0) after 1m0s
[10:17:40.710] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:21:0)
[10:17:40.710] Timer tick, asking Buildbucket for the build status
[10:17:40.889] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:40.889] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:22:0) after 1m0s
[10:18:40.919] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:22:0)
[10:18:40.919] Timer tick, asking Buildbucket for the build status
[10:18:41.046] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:41.046] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:23:0) after 1m0s
[10:19:41.105] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:23:0)
[10:19:41.105] Timer tick, asking Buildbucket for the build status
[10:19:41.265] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:41.265] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:24:0) after 1m0s
[10:20:41.285] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:24:0)
[10:20:41.285] Timer tick, asking Buildbucket for the build status
[10:20:41.404] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:41.405] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:25:0) after 1m0s
[10:21:41.442] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:25:0)
[10:21:41.442] Timer tick, asking Buildbucket for the build status
[10:21:41.590] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:41.590] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:26:0) after 1m0s
[10:22:41.617] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:26:0)
[10:22:41.617] Timer tick, asking Buildbucket for the build status
[10:22:41.732] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:41.732] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:27:0) after 1m0s
[10:23:41.798] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:27:0)
[10:23:41.798] Timer tick, asking Buildbucket for the build status
[10:23:41.921] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:41.921] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:28:0) after 1m0s
[10:24:41.935] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:28:0)
[10:24:41.935] Timer tick, asking Buildbucket for the build status
[10:24:42.049] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:42.049] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:29:0) after 1m0s
[10:25:42.093] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:29:0)
[10:25:42.093] Timer tick, asking Buildbucket for the build status
[10:25:42.373] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:42.373] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:30:0) after 1m0s
[10:26:42.432] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:30:0)
[10:26:42.432] Timer tick, asking Buildbucket for the build status
[10:26:42.616] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:42.616] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:31:0) after 1m0s
[10:27:42.787] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:31:0)
[10:27:42.787] Timer tick, asking Buildbucket for the build status
[10:27:42.865] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:42.865] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:32:0) after 1m0s
[10:28:42.933] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:32:0)
[10:28:42.933] Timer tick, asking Buildbucket for the build status
[10:28:43.046] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:43.046] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:33:0) after 1m0s
[10:29:43.069] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:33:0)
[10:29:43.069] Timer tick, asking Buildbucket for the build status
[10:29:43.145] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:43.145] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:34:0) after 1m0s
[10:30:43.206] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:34:0)
[10:30:43.206] Timer tick, asking Buildbucket for the build status
[10:30:43.279] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:43.279] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:35:0) after 1m0s
[10:31:43.375] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:35:0)
[10:31:43.375] Timer tick, asking Buildbucket for the build status
[10:31:43.435] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:43.435] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:36:0) after 1m0s
[10:32:43.508] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:36:0)
[10:32:43.508] Timer tick, asking Buildbucket for the build status
[10:32:43.918] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:43.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:37:0) after 1m0s
[10:33:43.941] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:37:0)
[10:33:43.941] Timer tick, asking Buildbucket for the build status
[10:33:44.351] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:44.351] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:38:0) after 1m0s
[10:34:44.436] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:38:0)
[10:34:44.436] Timer tick, asking Buildbucket for the build status
[10:34:44.505] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:44.505] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:39:0) after 1m0s
[10:35:44.529] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:39:0)
[10:35:44.529] Timer tick, asking Buildbucket for the build status
[10:35:44.598] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:44.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:40:0) after 1m0s
[10:36:44.782] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:40:0)
[10:36:44.782] Timer tick, asking Buildbucket for the build status
[10:36:45.120] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:45.120] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:41:0) after 1m0s
[10:37:45.148] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:41:0)
[10:37:45.148] Timer tick, asking Buildbucket for the build status
[10:37:45.489] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:45.489] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:42:0) after 1m0s
[10:38:45.529] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:42:0)
[10:38:45.530] Timer tick, asking Buildbucket for the build status
[10:38:45.802] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:45.802] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:43:0) after 1m0s
[10:39:45.876] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:43:0)
[10:39:45.876] Timer tick, asking Buildbucket for the build status
[10:39:46.110] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:46.110] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:44:0) after 1m0s
[10:40:46.186] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:44:0)
[10:40:46.186] Timer tick, asking Buildbucket for the build status
[10:40:46.274] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:46.274] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:45:0) after 1m0s
[10:41:46.352] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:45:0)
[10:41:46.352] Timer tick, asking Buildbucket for the build status
[10:41:46.876] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:46.876] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:46:0) after 1m0s
[10:42:46.914] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:46:0)
[10:42:46.914] Timer tick, asking Buildbucket for the build status
[10:42:47.239] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:47.239] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:47:0) after 1m0s
[10:43:47.263] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:47:0)
[10:43:47.263] Timer tick, asking Buildbucket for the build status
[10:43:47.391] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:47.391] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:48:0) after 1m0s
[10:44:47.420] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:48:0)
[10:44:47.420] Timer tick, asking Buildbucket for the build status
[10:44:47.706] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:47.706] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:49:0) after 1m0s
[10:45:47.750] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:49:0)
[10:45:47.750] Timer tick, asking Buildbucket for the build status
[10:45:48.048] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:48.048] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:50:0) after 1m0s
[10:46:48.067] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:50:0)
[10:46:48.067] Timer tick, asking Buildbucket for the build status
[10:46:48.494] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:48.494] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:51:0) after 1m0s
[10:47:48.527] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:51:0)
[10:47:48.527] Timer tick, asking Buildbucket for the build status
[10:47:48.925] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:48.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:52:0) after 1m0s
[10:48:48.985] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:52:0)
[10:48:48.985] Timer tick, asking Buildbucket for the build status
[10:48:49.142] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:49.142] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:53:0) after 1m0s
[10:49:49.165] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:53:0)
[10:49:49.165] Timer tick, asking Buildbucket for the build status
[10:49:49.272] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:49.272] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:54:0) after 1m0s
[10:50:49.312] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:54:0)
[10:50:49.312] Timer tick, asking Buildbucket for the build status
[10:50:49.383] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:49.383] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:55:0) after 1m0s
[10:51:49.407] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:55:0)
[10:51:49.407] Timer tick, asking Buildbucket for the build status
[10:51:49.631] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:49.631] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:56:0) after 1m0s
[10:52:49.679] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:56:0)
[10:52:49.679] Timer tick, asking Buildbucket for the build status
[10:52:49.760] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:49.760] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:57:0) after 1m0s
[10:53:49.807] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:57:0)
[10:53:49.807] Timer tick, asking Buildbucket for the build status
[10:53:49.960] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:49.960] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:58:0) after 1m0s
[10:54:49.981] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:58:0)
[10:54:49.981] Timer tick, asking Buildbucket for the build status
[10:54:50.057] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:50.057] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:59:0) after 1m0s
[10:55:50.081] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:59:0)
[10:55:50.081] Timer tick, asking Buildbucket for the build status
[10:55:50.186] Build 8860855913206439024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:50.186] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026285975239096560:60:0) after 1m0s
[10:56:04.457] Received PubSub notification, asking Buildbucket for the build status
[10:56:04.746] Build 8860855913206439024: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[10:56:04.746] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1608029762212572",
"created_by": "project:chromium",
"created_ts": "1608026316406015",
"failure_reason": "BUILD_FAILURE",
"id": "8860855913206439024",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d04319f4d5a3cd956e565fa9e9bee25dfba82de6\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**events_unittests** failed.\"}, \"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\": \"d04319f4d5a3cd956e565fa9e9bee25dfba82de6\", \"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-us-central1-b-32-1-qsbv\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16238, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"cb8903b14336d86e3d0889a367df60f417910872\", \"got_dawn_revision\": \"43c39a1ba42a1b1bc94337b11fe744fc36663693\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"d04319f4d5a3cd956e565fa9e9bee25dfba82de6\", \"got_revision_cp\": \"refs/heads/master@{#837027}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"fa44979d90aac8cca42e2fee711d44f23c9c4d67\", \"got_v8_revision\": \"59570b2d16168c40c3594328c1403c733d97b93d\", \"got_v8_revision_cp\": \"refs/heads/8.9.150@{#1}\", \"got_webrtc_revision\": \"d7808f1c464a07c8f1e2f97ec7ee92fda998d590\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32804}\", \"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\": \"d04319f4d5a3cd956e565fa9e9bee25dfba82de6\", \"swarm_hashes_refs/heads/master(at){#837027}_without_patch\": {\"absl_hardening_tests\": \"3270c52fc6cb6e231ee4dd4000d6f9fdc4536be7\", \"accessibility_unittests\": \"3c4f5e068bc1c7877900563cc0b7257ef9fe2532\", \"angle_unittests\": \"2e8769d1b033da56341b9bef39b5100bce7369f1\", \"app_shell_unittests\": \"acfcc84110938f551016bd873f1b588615322b43\", \"aura_unittests\": \"b9b6da0664839ab1d778fb4905cb09b86d2d32d1\", \"base_unittests\": \"d85197ff00616e4b2d692d72b0b4b091ab0edcc1\", \"base_util_unittests\": \"527f7b2e7dbdaefd16bb260e5b75cf6faf82f4d6\", \"blink_common_unittests\": \"ce725513fc098034dfd6caf19ef1380ef0cc1a37\", \"blink_fuzzer_unittests\": \"5de0e21a40391d40e430d768a579ed282a1ab5d0\", \"blink_heap_unittests\": \"f0844e0f345bcfd1e424d52a6c4b9500d4e351d4\", \"blink_platform_unittests\": \"91749492f063df0cb89a7933a51a93e4ef2225cb\", \"blink_unittests\": \"2cf524518aedb3906dfd7e035e00989d0bb046e8\", \"boringssl_crypto_tests\": \"04a39fef01623809fe128fbaca38f45a2b066003\", \"boringssl_ssl_tests\": \"cb7f7b99d642ae669c040b41e21f45ec04592220\", \"browser_switcher_bho_unittests\": \"9dd2627e3cc821fc7c38331744424776730fcaa4\", \"browser_tests\": \"61f0c221c121f9263bb0cd5cb8d232d9d82d027f\", \"capture_unittests\": \"23c038048ac2b40bf58f33aebc4b21d97e9127ab\", \"cast_unittests\": \"616c1e0e34ecdf4e81aa6beec6757b3c1a1f6f92\", \"cc_unittests\": \"316792231e91c1d5c2e833a05a9c7aee65546697\", \"chrome_app_unittests\": \"c0faa1dd60f4a02ae824e062aff49e074e7fc30c\", \"chrome_cleaner_unittests\": \"11ec5a5d0167d1f13d105887728db8d6de2d78b8\", \"chrome_elf_unittests\": \"ef3ad568fab324d4343966bffe0875df6c6d06cd\", \"chromedriver_unittests\": \"eed760e0e163442f6c86c8eb5d70cb8fbae761a8\", \"components_browsertests\": \"0fadb0e796e8df96c6aab0d1f4fcc8c9c938ac7c\", \"components_unittests\": \"2fabae9a1a154e19322c3042b38917429ca03007\", \"compositor_unittests\": \"43e9124e6e9276475cbfb6644ba4f4de530c22a2\", \"content_browsertests\": \"739b7e569b9ba425ce1988de480b3ce7b9951918\", \"content_unittests\": \"bd0636a7de653fd96216cc55646d372ece629529\", \"courgette_unittests\": \"38620d83c8e25492ea028c48c9f7089248cbc66c\", \"crashpad_tests\": \"5e47e2ddecbe2c86aaa89f35c19fd72b6f2bca62\", \"cronet_tests\": \"42a38e797e379973348dca4fa049a5ae94b446d6\", \"cronet_unittests\": \"23990b37cdfc2c5e841ec57598aad87c13c8c692\", \"crypto_unittests\": \"15b908e9459a595c3e52679963c07cfff7f26ac3\", \"delayloads_unittests\": \"99d5dc7755ccad768e9ac94ef0dd60006a4a8177\", \"device_unittests\": \"d976dcd1d07eb9a2f2370a28b46d978bf03d6bb0\", \"display_unittests\": \"57a4775b8f76cb7da953db7a380e361b7fe8367b\", \"elevation_service_unittests\": \"54167bbe115b0d4e60e1d47e6515d5fe6b6339c3\", \"events_unittests\": \"442e7bb4f55470b69f697d3137994359313773fc\", \"extensions_browsertests\": \"c26b7bebc54598bb63fd0c03608d42744b81975a\", \"extensions_unittests\": \"ae338f66a8585a1663b626eba24101adf8285465\", \"filesystem_service_unittests\": \"80c435674e6de7a7a8c40bf8f7ab972b2bd38f58\", \"gcm_unit_tests\": \"9c66f31770b5b7d93622293a53731cf707cb71bf\", \"gcp_unittests\": \"60aa4261dab4e9a8cf4bb25c37be61222730717a\", \"gfx_unittests\": \"07c8de137f135facbe9fad625b1366975e4fe2b5\", \"gin_unittests\": \"056ba9353271b87913d71ebdbf5956e55154fb3c\", \"google_apis_unittests\": \"14a91b7a958be2aac5b6ab70b2415f3e58b494e2\", \"gpu_unittests\": \"dcfe58361927c61c16e6fed5c17f9fe8d12af3b0\", \"gwp_asan_unittests\": \"fef7527cd133a6ce83a0fa788ce2842bad0a2396\", \"headless_browsertests\": \"409cb96b8fa38f0d9e470266a7b30093292e10a8\", \"headless_unittests\": \"4868fac93933e1c1873daeb0c07f6e41909be262\", \"install_static_unittests\": \"a789028befb5ee21fdabebcf3522a79d2778121c\", \"installer_util_unittests\": \"de0ab4816743f02296c7d2912db3c980383eea11\", \"interactive_ui_tests\": \"4dfd86d113a9db0f2dfacadb93fd82a123bc0820\", \"ipc_tests\": \"e9c3ab7f9eb30458b1dcda2b56a67b6411a72dfe\", \"jingle_unittests\": \"1d8cd50c8037be09e0219056e73468733dd2f8c4\", \"latency_unittests\": \"f036c5916a088b20fd11fa26eb1160081127cae6\", \"libjingle_xmpp_unittests\": \"d949b2438f7db9836f39442b9f1075408579d735\", \"liburlpattern_unittests\": \"60c43cc1be07d632b63f4a766dba507682886e3e\", \"media_blink_unittests\": \"90c6fee5a79c98d8294f7f0ba75403471bfdc6bf\", \"media_unittests\": \"f1a38466860cf3e7e08a6c7a3d4ed340863ef01c\", \"message_center_unittests\": \"33c43fa8eb1e4dce496b64bc8087d4f6941a5e45\", \"midi_unittests\": \"341a3f09d821e36e4ae0608e50799db197d2c49f\", \"mojo_core_unittests\": \"b9489b57cb82dcc68d975dc2688445dbd2def7aa\", \"mojo_unittests\": \"19ad9649dd2ffccc41463c90476a61d11d16c609\", \"nacl_loader_unittests\": \"999477ca50fdefb9cb0a002ac264591bfe1e09e6\", \"native_theme_unittests\": \"528652bc865b2c73c7410177282122bcf393da9a\", \"net_unittests\": \"0bdbb8fb72ddcb3259106da7a53e62675e1a1f2d\", \"notification_helper_unittests\": \"35bd6e866116a3276684fc55be49ae457c6beb02\", \"pdf_unittests\": \"0cf6bbb61cbf2ef7ed4d1dcb5da77cf381bc28ec\", \"perfetto_unittests\": \"7561bcf875d97186360269487eaf4c1bbfb5ec12\", \"ppapi_unittests\": \"2794fec415121e0df44c920e98f59dadb8a778e5\", \"printing_unittests\": \"1487c1cd6833d5730c478fefd938a9b2293ba974\", \"remoting_unittests\": \"ef82b39fa995bf949ba1d589a452456d8ea2238a\", \"sbox_integration_tests\": \"81cf740689ce3893a04f315dea04824907608818\", \"sbox_unittests\": \"eb0628034c680d67e84e5ef306fcbcf06baf6cc4\", \"sbox_validation_tests\": \"95f667646f813b5765819d2cfba72b6ae0d2d1d4\", \"service_manager_unittests\": \"437d4f0d3b972aa4b3a520642370dfba038164c1\", \"services_unittests\": \"cfbb12739d6abff49326e34a8f7809616bd03ce6\", \"setup_unittests\": \"0905aafc96b654a6bc79952762b81a5399ed57d8\", \"shell_dialogs_unittests\": \"247a644448814b662a72aeff2a4f5da5c26e6afb\", \"skia_unittests\": \"e5e50fa15b7ed1df2a2f33a06655c21bbb960f26\", \"snapshot_unittests\": \"886aa1798ce21674292042e4c492d0a17b2b37be\", \"sql_unittests\": \"4d236a37d7ca56195fb52da3d23b309c501da859\", \"storage_unittests\": \"93e271a108a377e3c171190c5d7d911466b2cf2d\", \"sync_integration_tests\": \"02122c9ca6a76d0f1d427c843b3b3737f98f50bc\", \"traffic_annotation_auditor_unittests\": \"3c923d0f878f73329dc9aba6ca63bf7fea6e70bb\", \"ui_base_unittests\": \"93d1dcc579683de704431c812195058737d95f24\", \"ui_touch_selection_unittests\": \"0682c64e8b9d9c8421cc6251646a35034b1b15d1\", \"unit_tests\": \"dc5c21bb524557c3612f47cd6b2e71884c4782b9\", \"updater_tests\": \"9631f6148cf7c355de39062c627543a7059418fb\", \"url_unittests\": \"26c8974374a6da1851b904f84baa7796bf35caf3\", \"views_unittests\": \"d35829097722f5794a8a33d2728991bd3b4cc6b4\", \"viz_unittests\": \"ba98a6065f06d386ace60e50e1effa256c1a2e9b\", \"vr_common_unittests\": \"eed4304df7121720e29c3cf09c993170a4add278\", \"vr_pixeltests\": \"78bcc39a55fd5e940e2995ca58ad10896a63a2d1\", \"weblayer_browsertests\": \"5bdc6dfd0d4f61e78c1a1a09a029fe79fcd77dbb\", \"weblayer_unittests\": \"68aa84b1588890ca7b55d2e0661559a6877b1b24\", \"wm_unittests\": \"1068e6871d94e891c285055e04bebdffee17310f\", \"wtf_unittests\": \"73eadf0efdcb88ed63af8031ac9727bf571aefa8\", \"zlib_unittests\": \"5bcf3e68572aceb4515a3c628760f92086aafcdf\", \"zucchini_unittests\": \"783ea13f0eca68350ab88b891d9ac1ec2814ba10\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_91c730c3e6ead475130e2ae78bc3f03e09cb9dcb91c3469d4450b29020110401_v2\", \"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-us-central1-b-32-1-qsbv\"], \"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-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**events_unittests** failed.\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1608026317869853",
"status": "COMPLETED",
"status_changed_ts": "1608026318237767",
"tags": [
"build_address:luci.chromium.ci/win-asan/16238",
"builder:win-asan",
"buildset:commit/git/d04319f4d5a3cd956e565fa9e9bee25dfba82de6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d04319f4d5a3cd956e565fa9e9bee25dfba82de6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026285975239096560",
"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/8860855913206439024/+/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:507ea7e95114c010",
"user_agent:luci-scheduler"
],
"updated_ts": "1608029764020683",
"url": "https://ci.chromium.org/b/8860855913206439024",
"utcnow_ts": "1608029764729892"
}
}
[10:56:04.746] Invocation finished in 57m29.662016044s with status FAILED