[22:32:42.470] New invocation is queued and will start shortly
[22:32:44.620] Starting the invocation (attempt 1)
[22:32:44.652] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:32:44.652] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:32:44.652] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021074502850217600",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"1053e5b0c320349f495f0e6037fe2fc8d5d0fe12\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9021074502850217600",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/1053e5b0c320349f495f0e6037fe2fc8d5d0fe12",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1053e5b0c320349f495f0e6037fe2fc8d5d0fe12",
"gitiles_ref:refs/heads/master"
]
}
[22:32:46.292] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612996364791490",
"id": "8855644439750847520",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1053e5b0c320349f495f0e6037fe2fc8d5d0fe12\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612996365934753",
"tags": [
"build_address:luci.chromium.ci/win-asan/17276",
"builder:win-asan",
"buildset:commit/git/1053e5b0c320349f495f0e6037fe2fc8d5d0fe12",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1053e5b0c320349f495f0e6037fe2fc8d5d0fe12",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021074502850217600",
"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/8855644439750847520/+/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": "1612996365936481",
"url": "https://ci.chromium.org/b/8855644439750847520",
"utcnow_ts": "1612996366253617"
}
}
[22:32:46.292] Task URL: https://ci.chromium.org/b/8855644439750847520
[22:32:46.292] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:2:0) after 1m0s
[22:32:49.758] Received PubSub notification, asking Buildbucket for the build status
[22:32:49.992] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:46.487] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:2:0)
[22:33:46.487] Timer tick, asking Buildbucket for the build status
[22:33:46.565] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:46.565] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:4:0) after 1m0s
[22:34:46.587] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:4:0)
[22:34:46.587] Timer tick, asking Buildbucket for the build status
[22:34:46.670] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:46.670] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:5:0) after 1m0s
[22:35:46.717] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:5:0)
[22:35:46.717] Timer tick, asking Buildbucket for the build status
[22:35:46.908] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:46.908] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:6:0) after 1m0s
[22:36:47.038] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:6:0)
[22:36:47.038] Timer tick, asking Buildbucket for the build status
[22:36:47.154] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:47.154] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:7:0) after 1m0s
[22:37:47.214] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:7:0)
[22:37:47.214] Timer tick, asking Buildbucket for the build status
[22:37:47.534] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:47.534] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:8:0) after 1m0s
[22:38:47.598] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:8:0)
[22:38:47.598] Timer tick, asking Buildbucket for the build status
[22:38:48.012] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:48.012] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:9:0) after 1m0s
[22:39:48.108] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:9:0)
[22:39:48.108] Timer tick, asking Buildbucket for the build status
[22:39:48.202] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:48.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:10:0) after 1m0s
[22:40:48.256] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:10:0)
[22:40:48.257] Timer tick, asking Buildbucket for the build status
[22:40:48.339] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:48.339] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:11:0) after 1m0s
[22:41:48.405] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:11:0)
[22:41:48.405] Timer tick, asking Buildbucket for the build status
[22:41:48.547] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:48.547] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:12:0) after 1m0s
[22:42:48.602] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:12:0)
[22:42:48.602] Timer tick, asking Buildbucket for the build status
[22:42:48.920] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:48.920] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:13:0) after 1m0s
[22:43:48.990] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:13:0)
[22:43:48.990] Timer tick, asking Buildbucket for the build status
[22:43:49.066] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:49.066] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:14:0) after 1m0s
[22:44:49.091] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:14:0)
[22:44:49.091] Timer tick, asking Buildbucket for the build status
[22:44:49.335] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:49.335] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:15:0) after 1m0s
[22:45:49.371] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:15:0)
[22:45:49.371] Timer tick, asking Buildbucket for the build status
[22:45:49.564] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:49.564] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:16:0) after 1m0s
[22:46:49.699] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:16:0)
[22:46:49.699] Timer tick, asking Buildbucket for the build status
[22:46:50.143] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:50.143] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:17:0) after 1m0s
[22:47:50.178] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:17:0)
[22:47:50.178] Timer tick, asking Buildbucket for the build status
[22:47:50.279] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:50.279] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:18:0) after 1m0s
[22:48:50.965] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:18:0)
[22:48:50.965] Timer tick, asking Buildbucket for the build status
[22:48:51.073] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:51.073] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:19:0) after 1m0s
[22:49:51.230] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:19:0)
[22:49:51.230] Timer tick, asking Buildbucket for the build status
[22:49:51.517] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:51.517] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:20:0) after 1m0s
[22:50:51.533] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:20:0)
[22:50:51.533] Timer tick, asking Buildbucket for the build status
[22:50:51.915] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:51.915] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:21:0) after 1m0s
[22:51:52.010] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:21:0)
[22:51:52.010] Timer tick, asking Buildbucket for the build status
[22:51:52.079] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:52.079] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:22:0) after 1m0s
[22:52:52.165] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:22:0)
[22:52:52.165] Timer tick, asking Buildbucket for the build status
[22:52:52.320] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:52.320] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:23:0) after 1m0s
[22:53:52.387] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:23:0)
[22:53:52.387] Timer tick, asking Buildbucket for the build status
[22:53:52.471] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:52.472] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:24:0) after 1m0s
[22:54:52.505] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:24:0)
[22:54:52.505] Timer tick, asking Buildbucket for the build status
[22:54:52.602] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:52.602] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:25:0) after 1m0s
[22:55:52.622] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:25:0)
[22:55:52.622] Timer tick, asking Buildbucket for the build status
[22:55:52.848] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:52.848] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:26:0) after 1m0s
[22:56:52.995] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:26:0)
[22:56:52.995] Timer tick, asking Buildbucket for the build status
[22:56:53.239] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:53.239] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:27:0) after 1m0s
[22:57:53.256] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:27:0)
[22:57:53.256] Timer tick, asking Buildbucket for the build status
[22:57:53.350] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:53.350] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:28:0) after 1m0s
[22:58:53.330] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:28:0)
[22:58:53.330] Timer tick, asking Buildbucket for the build status
[22:58:53.446] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:53.446] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:29:0) after 1m0s
[22:59:53.470] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:29:0)
[22:59:53.470] Timer tick, asking Buildbucket for the build status
[22:59:53.895] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:53.895] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:30:0) after 1m0s
[23:00:53.933] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:30:0)
[23:00:53.933] Timer tick, asking Buildbucket for the build status
[23:00:54.042] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:54.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:31:0) after 1m0s
[23:01:54.128] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:31:0)
[23:01:54.128] Timer tick, asking Buildbucket for the build status
[23:01:54.224] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:54.224] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:32:0) after 1m0s
[23:02:54.319] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:32:0)
[23:02:54.319] Timer tick, asking Buildbucket for the build status
[23:02:54.531] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:54.531] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:33:0) after 1m0s
[23:03:54.627] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:33:0)
[23:03:54.627] Timer tick, asking Buildbucket for the build status
[23:03:54.740] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:54.740] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:34:0) after 1m0s
[23:04:54.782] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:34:0)
[23:04:54.782] Timer tick, asking Buildbucket for the build status
[23:04:54.950] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:54.951] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:35:0) after 1m0s
[23:05:55.041] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:35:0)
[23:05:55.041] Timer tick, asking Buildbucket for the build status
[23:05:55.341] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:55.341] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:36:0) after 1m0s
[23:06:55.396] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:36:0)
[23:06:55.396] Timer tick, asking Buildbucket for the build status
[23:06:55.507] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:55.507] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:37:0) after 1m0s
[23:07:55.592] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:37:0)
[23:07:55.592] Timer tick, asking Buildbucket for the build status
[23:07:55.676] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:55.676] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:38:0) after 1m0s
[23:08:55.669] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:38:0)
[23:08:55.669] Timer tick, asking Buildbucket for the build status
[23:08:55.806] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:55.806] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:39:0) after 1m0s
[23:09:55.929] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:39:0)
[23:09:55.929] Timer tick, asking Buildbucket for the build status
[23:09:56.087] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:56.087] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:40:0) after 1m0s
[23:10:56.214] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:40:0)
[23:10:56.214] Timer tick, asking Buildbucket for the build status
[23:10:56.297] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:56.297] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:41:0) after 1m0s
[23:11:56.351] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:41:0)
[23:11:56.351] Timer tick, asking Buildbucket for the build status
[23:11:56.440] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:56.440] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:42:0) after 1m0s
[23:12:56.517] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:42:0)
[23:12:56.517] Timer tick, asking Buildbucket for the build status
[23:12:56.933] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:56.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:43:0) after 1m0s
[23:13:57.114] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:43:0)
[23:13:57.114] Timer tick, asking Buildbucket for the build status
[23:13:57.217] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:57.217] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:44:0) after 1m0s
[23:14:57.302] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:44:0)
[23:14:57.302] Timer tick, asking Buildbucket for the build status
[23:14:57.439] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:57.439] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:45:0) after 1m0s
[23:15:57.492] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:45:0)
[23:15:57.492] Timer tick, asking Buildbucket for the build status
[23:15:57.582] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:57.582] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:46:0) after 1m0s
[23:16:57.629] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:46:0)
[23:16:57.629] Timer tick, asking Buildbucket for the build status
[23:16:57.751] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:57.751] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:47:0) after 1m0s
[23:17:57.781] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:47:0)
[23:17:57.781] Timer tick, asking Buildbucket for the build status
[23:17:57.877] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:57.877] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:48:0) after 1m0s
[23:18:57.951] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:48:0)
[23:18:57.951] Timer tick, asking Buildbucket for the build status
[23:18:58.102] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:58.102] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:49:0) after 1m0s
[23:19:58.118] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:49:0)
[23:19:58.118] Timer tick, asking Buildbucket for the build status
[23:19:58.228] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:58.228] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:50:0) after 1m0s
[23:20:58.313] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:50:0)
[23:20:58.313] Timer tick, asking Buildbucket for the build status
[23:20:58.691] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:58.691] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:51:0) after 1m0s
[23:21:58.976] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:51:0)
[23:21:58.976] Timer tick, asking Buildbucket for the build status
[23:21:59.224] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:59.224] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:52:0) after 1m0s
[23:22:59.378] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:52:0)
[23:22:59.378] Timer tick, asking Buildbucket for the build status
[23:22:59.637] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:59.637] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:53:0) after 1m0s
[23:23:59.753] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:53:0)
[23:23:59.753] Timer tick, asking Buildbucket for the build status
[23:23:59.880] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:59.880] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:54:0) after 1m0s
[23:25:00.088] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:54:0)
[23:25:00.088] Timer tick, asking Buildbucket for the build status
[23:25:00.170] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:00.170] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:55:0) after 1m0s
[23:26:04.383] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:55:0)
[23:26:04.383] Timer tick, asking Buildbucket for the build status
[23:26:04.516] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:04.516] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:56:0) after 1m0s
[23:27:05.003] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:56:0)
[23:27:05.003] Timer tick, asking Buildbucket for the build status
[23:27:05.611] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:05.611] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:57:0) after 1m0s
[23:28:05.923] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:57:0)
[23:28:05.923] Timer tick, asking Buildbucket for the build status
[23:28:06.404] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:06.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:58:0) after 1m0s
[23:29:06.447] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:58:0)
[23:29:06.447] Timer tick, asking Buildbucket for the build status
[23:29:06.572] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:06.572] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:59:0) after 1m0s
[23:30:06.628] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:59:0)
[23:30:06.628] Timer tick, asking Buildbucket for the build status
[23:30:06.719] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:06.719] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:60:0) after 1m0s
[23:31:06.780] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:60:0)
[23:31:06.780] Timer tick, asking Buildbucket for the build status
[23:31:06.869] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:06.869] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:61:0) after 1m0s
[23:32:06.963] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:61:0)
[23:32:06.963] Timer tick, asking Buildbucket for the build status
[23:32:07.049] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:07.049] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:62:0) after 1m0s
[23:33:07.067] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:62:0)
[23:33:07.067] Timer tick, asking Buildbucket for the build status
[23:33:07.309] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:07.309] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:63:0) after 1m0s
[23:34:07.299] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:63:0)
[23:34:07.299] Timer tick, asking Buildbucket for the build status
[23:34:07.419] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:07.419] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:64:0) after 1m0s
[23:35:07.522] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:64:0)
[23:35:07.522] Timer tick, asking Buildbucket for the build status
[23:35:07.599] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:07.599] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:65:0) after 1m0s
[23:36:07.667] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:65:0)
[23:36:07.667] Timer tick, asking Buildbucket for the build status
[23:36:07.932] Build 8855644439750847520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:07.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021074502850217600:66:0) after 1m0s
[23:36:20.274] Received PubSub notification, asking Buildbucket for the build status
[23:36:20.371] Build 8855644439750847520: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[23:36:20.371] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1613000177628759",
"created_by": "project:chromium",
"created_ts": "1612996364791490",
"failure_reason": "BUILD_FAILURE",
"id": "8855644439750847520",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1053e5b0c320349f495f0e6037fe2fc8d5d0fe12\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- PhishingClassifierTest.TestSendsVisualHash\\n\\n- ScriptContextTest.GetEffectiveDocumentURL\\n\\n- PhishingDOMFeatureExtractorTest.SubFrames\\n\\n- MSE_ExternalClearKey/EncryptedMediaTest.InvalidResponseKeyError/0\\n\\n- ...1 more failure(s) (5 total)...\"}, \"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\": \"1053e5b0c320349f495f0e6037fe2fc8d5d0fe12\", \"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-5-o33n\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17276, \"clang_revision\": \"llvmorg-13-init-794-g83e2710e-1\", \"got_angle_revision\": \"a8a2a71b3ab5e535def6239997f6f24da918556b\", \"got_dawn_revision\": \"e0a588752c827daef74960bbc80d7f5c4f73c4dd\", \"got_nacl_revision\": \"4de55da44c4b511989359381e8b7a88a01fe0634\", \"got_revision\": \"1053e5b0c320349f495f0e6037fe2fc8d5d0fe12\", \"got_revision_cp\": \"refs/heads/master@{#852843}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"673a7fe5c3ff6686c98951cafb24a93ca5bd1303\", \"got_v8_revision\": \"4a2d4d761da903dbfbbfcddb6fa50dc1b27c7667\", \"got_v8_revision_cp\": \"refs/heads/9.0.159@{#1}\", \"got_webrtc_revision\": \"7bad75b3906ae78b67b2a8cec095d877deb58215\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33214}\", \"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\": \"1053e5b0c320349f495f0e6037fe2fc8d5d0fe12\", \"swarm_hashes_refs/heads/master(at){#852843}_without_patch\": {\"absl_hardening_tests\": \"0022317bfa9cfa557997d39b0141c67cdc970d2a\", \"accessibility_unittests\": \"971e848975e887175aa7fef2b35d597a8aaecf4a\", \"angle_unittests\": \"fbbcd323b45e072250c020bc13007fa404d4c9f5\", \"app_shell_unittests\": \"2f4f99cd67c7a315b94a57622c4d9379eddff4f0\", \"aura_unittests\": \"ecc5f66b7cedc8cb209c2eeec607f66d1f83b44a\", \"base_unittests\": \"093b0ec72f28aaf0767d1741db5f5f1d472f2153\", \"base_util_unittests\": \"3bf2c98170642f68aff4d1d7a61fb4e5b2b3a6eb\", \"blink_common_unittests\": \"0030fd306a2df24ae607f3155a541d1a079b744e\", \"blink_fuzzer_unittests\": \"b4ca1da7ef188af392cbfd65dd1fe8dfbb8ff7a0\", \"blink_heap_unittests\": \"c02f7c4484635caa02694bf60b62df3c287c096b\", \"blink_platform_unittests\": \"a0ce034ceb80a81c327af9aa47f214e6d0f0b0b7\", \"blink_unittests\": \"3d0f6630f2ba0cda74dbfa45c0e595b61e4c33a0\", \"boringssl_crypto_tests\": \"ccc46f932e78626e02dc17d58ea2ee663643bb3a\", \"boringssl_ssl_tests\": \"d2e1f1e9929e3ddeb137571264d68704cca536fe\", \"browser_switcher_bho_unittests\": \"b5a8da9815a0a7bf66278206df0900eecdd1bfef\", \"browser_tests\": \"82a12971acc0ba0a965f82fc0c4cd1ba6dabd067\", \"capture_unittests\": \"8d7d9ce15a0f7aa3d2eeb90c12292c2215cad4ce\", \"cast_unittests\": \"fe0b91a43071c2ca40030d685db14a0b67ce478a\", \"cc_unittests\": \"753bd11386dc166721ead4761e9d3142aef0df25\", \"chrome_app_unittests\": \"42dd4a8f2bd680ac5667f96cdd064f36ea20cd1c\", \"chrome_cleaner_unittests\": \"f55e7da80b8c8028a4c12d476568adaf1ad9d9b5\", \"chrome_elf_unittests\": \"d994b944b02f1f67d745f41d431b5cce15ab4b5b\", \"chromedriver_unittests\": \"83f5f530ed6dd4649c083cc2a2b374d43def91de\", \"components_browsertests\": \"6c50ca762d043f43954e11e62608c2df576cc710\", \"components_unittests\": \"cabc8fbac2c733d46bcaed33f0dd78e7a34d333a\", \"compositor_unittests\": \"e0499cce02c3fb60acc20837196d5bd4ad0b5776\", \"content_browsertests\": \"4150e24a87bc124aaf4d7c55bee7456ba510856d\", \"content_unittests\": \"84e39280e22c88c2157f625370967bbced745d1c\", \"courgette_unittests\": \"8673cbddf06614f2db0be74170e98bd534eb4961\", \"crashpad_tests\": \"811cf0f9133684a048482b16764758371413ab86\", \"cronet_tests\": \"cb105d96530e644ef34a1d9d72989fbb5ecc17b6\", \"cronet_unittests\": \"bb66369a89f06d40adaffbf7aa2fb4d58284fc94\", \"crypto_unittests\": \"076294dad0a23bc52d707050be737f21005e8830\", \"delayloads_unittests\": \"4688b4c8fd27c856c57b1f0284b9b05c6691a6fc\", \"device_unittests\": \"15e33c7a49db4867289c15ab45fe6a9152b8b71d\", \"display_unittests\": \"d2a607daf9b13a442dfe70328de853656df78422\", \"elevation_service_unittests\": \"b0d9937c8e0163b4939ba216a234b22b5af427ff\", \"events_unittests\": \"dc36b0900959b18518ec7e6135922036c92304be\", \"extensions_browsertests\": \"128da27e1425a037d45018df841cb292aab83bff\", \"extensions_unittests\": \"fc341c5c236a3bbbf844d7ee19944345010af06b\", \"filesystem_service_unittests\": \"b0ae87724e9e70c2232fccec729fca2bbc5ca3fb\", \"gcm_unit_tests\": \"c793faf410f2b38beaf7d988d1aba67874448c72\", \"gcp_unittests\": \"73e96700d6603290e2a8bfae01455dad4c9e0a9f\", \"gfx_unittests\": \"a859bea761ca87b1348fbc311b7e2141271c53e5\", \"gin_unittests\": \"44681ce8afd58ed219ade9d73faaf3a498589561\", \"google_apis_unittests\": \"d09459d11bd35e1aeee90dda182d89a4672c8caa\", \"gpu_unittests\": \"81d74af2d6d87f4831ae4be0b7a814c90a6dced2\", \"gwp_asan_unittests\": \"f290aa132224bff4da5823e1b97b9820d22f8bd1\", \"headless_browsertests\": \"4243ab1190d63957290a1498c2e7d9b7ab322c90\", \"headless_unittests\": \"d1857db2b3e6aa5ed521a3829533a268ba65d840\", \"install_static_unittests\": \"948e3d8bb879ceb37ae68b37b7b3f80f3f3506dd\", \"installer_util_unittests\": \"28f01754ccdbd8bb8283a65b7964182354c57bd4\", \"interactive_ui_tests\": \"9c5f1a09cf2beebf99c59727bd260012a3222ea8\", \"ipc_tests\": \"3645a7223bd9e3a317e34efbe033ba9efcf02b4b\", \"jingle_unittests\": \"6438a643141d74b1bb55554f75f4b38244034746\", \"latency_unittests\": \"00d573d051b0f1c939c33094853bd042b5868238\", \"libjingle_xmpp_unittests\": \"d1936768ec67c5acb9a2aea8450e816b7ca3a391\", \"liburlpattern_unittests\": \"dece0a45483c1dbd9504b7e60eb50627abd5f599\", \"media_blink_unittests\": \"4f2559b8620fce45448764bf3193901ea780f16c\", \"media_unittests\": \"a88e84f5072585f33de9308f001476f17911252f\", \"message_center_unittests\": \"e7fa1e4b4cfdad054e22659bbd5bbc5ec82d3a1b\", \"midi_unittests\": \"1a142a26aab4003ba06b4862f3e279bc19d37301\", \"mojo_core_unittests\": \"0cb57cec8dac050c1e11b39a759ae024de37355b\", \"mojo_unittests\": \"a209c7281b379db6a8a1cd96dd1a7d6c3432d76b\", \"nacl_loader_unittests\": \"9c442f9806d527a59085197c18697911e4b01119\", \"native_theme_unittests\": \"e6d6db7e949eb22e55f548552b7794acd95eb6ff\", \"net_unittests\": \"ad9a0fe5c525c05d7ced7d1dd648d869844115a3\", \"notification_helper_unittests\": \"687ab93873321ae6083d5caa817a4e3c6121dded\", \"pdf_unittests\": \"0f168aaae8e3ed81bf48b42611b30dbef4560ee2\", \"perfetto_unittests\": \"86b4fe65798a8e9e96edf456395f5bc2aad37fc9\", \"ppapi_unittests\": \"61d36dd057f63477f47f66dcf3901144d107e450\", \"printing_unittests\": \"55449976f65612da6bee42075b99f65ffb5e5833\", \"remoting_unittests\": \"118bc2c36a694f42dddf0fb99329594879a1da12\", \"sbox_integration_tests\": \"1d7af4490e02cdc993d8867f60df92ecd49de7b0\", \"sbox_unittests\": \"876c63e4c517c3dcb01cf5162f9c8a8cb6cfac5e\", \"sbox_validation_tests\": \"743a3daa20c58c8b6e1647a80674a60a530cdb76\", \"service_manager_unittests\": \"dd1959fee5c2662582c3370fddeaef902ece2017\", \"services_unittests\": \"dcdc00144720a3490189c8527b8d431c55fbef5b\", \"setup_unittests\": \"5d4857a5a05821777ead2b7d3ca065ad10426afe\", \"shell_dialogs_unittests\": \"267f24df7eda043749f75838386f1214a9fbb9a4\", \"skia_unittests\": \"a0833acc81369dc10d10a248a80b6faf23b12b60\", \"snapshot_unittests\": \"1a204ccf4bd1b4d35008a45705220b581b586473\", \"sql_unittests\": \"9b23c47db6e4647858e771219170ab439586388d\", \"storage_unittests\": \"5b332e337f0d201d023e134f70a78c707c1fe50f\", \"sync_integration_tests\": \"20dd3fc3a34ee3d53d843b67c11d232e21e0a715\", \"traffic_annotation_auditor_unittests\": \"ecec373916db19adc8c8d54f60bdf9966bfa8240\", \"ui_base_unittests\": \"140462259794ceb58c12434a1b877fa6fce973d0\", \"ui_touch_selection_unittests\": \"4ca5c769939e8a544c123223d0043616b0f4786a\", \"unit_tests\": \"f863d29f32166d5b0730f6351dc8924cb392ee2c\", \"updater_tests\": \"83057720e52670703d49d01b7dedd594cf5be130\", \"url_unittests\": \"82b8d8c9127d42d837a0bc3620330b0001fc044f\", \"views_unittests\": \"b5adc22e57434e7f8eaf384a742aa30e555af959\", \"viz_unittests\": \"b8fca01f625fe797ad3e40cf67b15b2e75ebb732\", \"vr_common_unittests\": \"5d6df128004f18ad5d1ac89dd7143d5b91194c22\", \"vr_pixeltests\": \"c49e88b0edbeeb28a343b4347e85790e418887a4\", \"weblayer_browsertests\": \"f95cf9ce364e721c02a20a1ff4e56dd4d8091b40\", \"weblayer_unittests\": \"83458ed4289c9f0211d76ae5fbe1466422dc4ea1\", \"wm_unittests\": \"04e3d0b80ff2dc0b1aab9d453cb7b305d78dc7fc\", \"wtf_unittests\": \"43b38245136e281a79cad0381d7e91e548f50e88\", \"zlib_unittests\": \"60e74e4343f7da66ce7f2c86be5a7f1fe1fb24d3\", \"zucchini_unittests\": \"e9486e550200b5ae7ac4ade1923085c1052af627\"}}, \"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-5-o33n\"], \"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\": [\"5685-1877293\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- PhishingClassifierTest.TestSendsVisualHash\\n\\n- ScriptContextTest.GetEffectiveDocumentURL\\n\\n- PhishingDOMFeatureExtractorTest.SubFrames\\n\\n- MSE_ExternalClearKey/EncryptedMediaTest.InvalidResponseKeyError/0\\n\\n- ...1 more failure(s) (5 total)...\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1612996369015110",
"status": "COMPLETED",
"status_changed_ts": "1612996369343938",
"tags": [
"build_address:luci.chromium.ci/win-asan/17276",
"builder:win-asan",
"buildset:commit/git/1053e5b0c320349f495f0e6037fe2fc8d5d0fe12",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1053e5b0c320349f495f0e6037fe2fc8d5d0fe12",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021074502850217600",
"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/8855644439750847520/+/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:51a6e4d7e12f9210",
"user_agent:luci-scheduler"
],
"updated_ts": "1613000178940778",
"url": "https://ci.chromium.org/b/8855644439750847520",
"utcnow_ts": "1613000180361911"
}
}
[23:36:20.371] Invocation finished in 1h3m37.919476034s with status FAILED