[13:38:34.895] New invocation is queued and will start shortly
[13:38:36.038] Starting the invocation (attempt 1)
[13:38:36.071] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:38:36.072] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:38:36.072] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019930346603316784",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"1c8e872e61eaf5333d7ec5b583a7306497536d88\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9019930346603316784",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/1c8e872e61eaf5333d7ec5b583a7306497536d88",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1c8e872e61eaf5333d7ec5b583a7306497536d88",
"gitiles_ref:refs/heads/master"
]
}
[13:38:37.483] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614087516178029",
"id": "8854500284594194288",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1c8e872e61eaf5333d7ec5b583a7306497536d88\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614087517001081",
"tags": [
"build_address:luci.chromium.ci/win-asan/17491",
"builder:win-asan",
"buildset:commit/git/1c8e872e61eaf5333d7ec5b583a7306497536d88",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1c8e872e61eaf5333d7ec5b583a7306497536d88",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019930346603316784",
"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/8854500284594194288/+/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": "1614087517001212",
"url": "https://ci.chromium.org/b/8854500284594194288",
"utcnow_ts": "1614087517472411"
}
}
[13:38:37.483] Task URL: https://ci.chromium.org/b/8854500284594194288
[13:38:37.484] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:2:0) after 1m0s
[13:38:42.854] Received PubSub notification, asking Buildbucket for the build status
[13:38:42.935] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:37.581] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:2:0)
[13:39:37.581] Timer tick, asking Buildbucket for the build status
[13:39:37.762] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:37.762] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:4:0) after 1m0s
[13:40:37.939] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:4:0)
[13:40:37.939] Timer tick, asking Buildbucket for the build status
[13:40:38.289] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:38.290] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:5:0) after 1m0s
[13:41:38.362] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:5:0)
[13:41:38.362] Timer tick, asking Buildbucket for the build status
[13:41:38.429] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:38.429] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:6:0) after 1m0s
[13:42:38.448] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:6:0)
[13:42:38.448] Timer tick, asking Buildbucket for the build status
[13:42:38.537] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:38.537] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:7:0) after 1m0s
[13:43:38.509] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:7:0)
[13:43:38.509] Timer tick, asking Buildbucket for the build status
[13:43:38.600] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:38.600] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:8:0) after 1m0s
[13:44:38.658] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:8:0)
[13:44:38.658] Timer tick, asking Buildbucket for the build status
[13:44:38.736] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:38.736] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:9:0) after 1m0s
[13:45:38.758] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:9:0)
[13:45:38.758] Timer tick, asking Buildbucket for the build status
[13:45:38.956] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:38.956] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:10:0) after 1m0s
[13:46:39.025] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:10:0)
[13:46:39.025] Timer tick, asking Buildbucket for the build status
[13:46:39.104] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:39.104] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:11:0) after 1m0s
[13:47:39.163] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:11:0)
[13:47:39.163] Timer tick, asking Buildbucket for the build status
[13:47:39.232] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:39.233] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:12:0) after 1m0s
[13:48:39.250] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:12:0)
[13:48:39.250] Timer tick, asking Buildbucket for the build status
[13:48:39.623] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:39.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:13:0) after 1m0s
[13:49:39.702] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:13:0)
[13:49:39.702] Timer tick, asking Buildbucket for the build status
[13:49:39.880] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:39.880] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:14:0) after 1m0s
[13:50:39.963] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:14:0)
[13:50:39.963] Timer tick, asking Buildbucket for the build status
[13:50:40.080] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:40.080] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:15:0) after 1m0s
[13:51:40.132] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:15:0)
[13:51:40.132] Timer tick, asking Buildbucket for the build status
[13:51:40.234] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:40.234] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:16:0) after 1m0s
[13:52:40.294] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:16:0)
[13:52:40.294] Timer tick, asking Buildbucket for the build status
[13:52:40.593] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:40.593] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:17:0) after 1m0s
[13:53:40.610] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:17:0)
[13:53:40.611] Timer tick, asking Buildbucket for the build status
[13:53:40.728] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:40.728] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:18:0) after 1m0s
[13:54:40.743] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:18:0)
[13:54:40.743] Timer tick, asking Buildbucket for the build status
[13:54:41.007] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:41.007] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:19:0) after 1m0s
[13:55:41.022] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:19:0)
[13:55:41.022] Timer tick, asking Buildbucket for the build status
[13:55:41.260] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:41.260] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:20:0) after 1m0s
[13:56:41.287] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:20:0)
[13:56:41.287] Timer tick, asking Buildbucket for the build status
[13:56:41.760] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:41.760] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:21:0) after 1m0s
[13:57:41.770] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:21:0)
[13:57:41.770] Timer tick, asking Buildbucket for the build status
[13:57:42.595] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:42.595] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:22:0) after 1m0s
[13:58:42.600] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:22:0)
[13:58:42.600] Timer tick, asking Buildbucket for the build status
[13:58:42.701] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:42.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:23:0) after 1m0s
[13:59:42.721] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:23:0)
[13:59:42.721] Timer tick, asking Buildbucket for the build status
[13:59:42.901] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:42.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:24:0) after 1m0s
[14:00:42.918] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:24:0)
[14:00:42.918] Timer tick, asking Buildbucket for the build status
[14:00:43.691] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:43.691] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:25:0) after 1m0s
[14:01:43.821] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:25:0)
[14:01:43.821] Timer tick, asking Buildbucket for the build status
[14:01:44.379] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:44.379] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:26:0) after 1m0s
[14:02:44.552] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:26:0)
[14:02:44.552] Timer tick, asking Buildbucket for the build status
[14:02:45.579] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:45.579] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:27:0) after 1m0s
[14:03:45.949] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:27:0)
[14:03:45.949] Timer tick, asking Buildbucket for the build status
[14:03:46.188] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:46.188] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:28:0) after 1m0s
[14:04:46.210] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:28:0)
[14:04:46.210] Timer tick, asking Buildbucket for the build status
[14:04:46.413] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:46.413] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:29:0) after 1m0s
[14:05:46.823] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:29:0)
[14:05:46.823] Timer tick, asking Buildbucket for the build status
[14:05:46.900] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:46.900] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:30:0) after 1m0s
[14:06:46.992] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:30:0)
[14:06:46.992] Timer tick, asking Buildbucket for the build status
[14:06:47.216] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:47.216] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:31:0) after 1m0s
[14:07:47.391] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:31:0)
[14:07:47.391] Timer tick, asking Buildbucket for the build status
[14:07:47.903] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:47.903] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:32:0) after 1m0s
[14:08:48.033] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:32:0)
[14:08:48.033] Timer tick, asking Buildbucket for the build status
[14:08:48.382] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:48.382] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:33:0) after 1m0s
[14:09:48.419] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:33:0)
[14:09:48.419] Timer tick, asking Buildbucket for the build status
[14:09:48.494] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:48.494] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:34:0) after 1m0s
[14:10:48.640] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:34:0)
[14:10:48.640] Timer tick, asking Buildbucket for the build status
[14:10:48.745] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:48.745] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:35:0) after 1m0s
[14:11:48.764] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:35:0)
[14:11:48.764] Timer tick, asking Buildbucket for the build status
[14:11:48.855] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:48.855] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:36:0) after 1m0s
[14:12:48.977] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:36:0)
[14:12:48.977] Timer tick, asking Buildbucket for the build status
[14:12:49.373] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:49.373] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:37:0) after 1m0s
[14:13:49.441] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:37:0)
[14:13:49.441] Timer tick, asking Buildbucket for the build status
[14:13:49.543] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:49.543] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:38:0) after 1m0s
[14:14:49.668] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:38:0)
[14:14:49.668] Timer tick, asking Buildbucket for the build status
[14:14:49.755] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:49.755] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:39:0) after 1m0s
[14:15:49.852] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:39:0)
[14:15:49.852] Timer tick, asking Buildbucket for the build status
[14:15:49.976] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:49.977] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:40:0) after 1m0s
[14:16:49.982] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:40:0)
[14:16:49.982] Timer tick, asking Buildbucket for the build status
[14:16:50.354] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:50.354] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:41:0) after 1m0s
[14:17:50.471] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:41:0)
[14:17:50.471] Timer tick, asking Buildbucket for the build status
[14:17:50.574] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:50.574] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:42:0) after 1m0s
[14:18:50.576] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:42:0)
[14:18:50.576] Timer tick, asking Buildbucket for the build status
[14:18:50.967] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:50.967] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:43:0) after 1m0s
[14:19:51.008] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:43:0)
[14:19:51.008] Timer tick, asking Buildbucket for the build status
[14:19:51.209] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:51.209] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:44:0) after 1m0s
[14:20:51.250] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:44:0)
[14:20:51.250] Timer tick, asking Buildbucket for the build status
[14:20:51.341] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:51.341] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:45:0) after 1m0s
[14:21:51.377] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:45:0)
[14:21:51.377] Timer tick, asking Buildbucket for the build status
[14:21:51.493] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:51.493] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:46:0) after 1m0s
[14:22:51.535] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:46:0)
[14:22:51.535] Timer tick, asking Buildbucket for the build status
[14:22:51.638] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:51.638] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:47:0) after 1m0s
[14:23:51.665] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:47:0)
[14:23:51.665] Timer tick, asking Buildbucket for the build status
[14:23:52.110] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:52.110] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:48:0) after 1m0s
[14:24:52.173] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:48:0)
[14:24:52.173] Timer tick, asking Buildbucket for the build status
[14:24:52.701] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:52.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:49:0) after 1m0s
[14:25:52.720] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:49:0)
[14:25:52.720] Timer tick, asking Buildbucket for the build status
[14:25:53.126] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:53.126] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:50:0) after 1m0s
[14:26:53.146] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:50:0)
[14:26:53.146] Timer tick, asking Buildbucket for the build status
[14:26:53.231] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:53.231] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:51:0) after 1m0s
[14:27:53.258] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:51:0)
[14:27:53.258] Timer tick, asking Buildbucket for the build status
[14:27:53.763] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:53.763] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:52:0) after 1m0s
[14:28:53.749] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:52:0)
[14:28:53.749] Timer tick, asking Buildbucket for the build status
[14:28:53.852] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:53.852] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:53:0) after 1m0s
[14:29:53.867] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:53:0)
[14:29:53.867] Timer tick, asking Buildbucket for the build status
[14:29:54.197] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:54.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:54:0) after 1m0s
[14:30:54.263] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:54:0)
[14:30:54.263] Timer tick, asking Buildbucket for the build status
[14:30:54.666] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:54.666] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:55:0) after 1m0s
[14:31:54.727] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:55:0)
[14:31:54.727] Timer tick, asking Buildbucket for the build status
[14:31:54.877] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:54.877] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:56:0) after 1m0s
[14:32:54.915] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:56:0)
[14:32:54.915] Timer tick, asking Buildbucket for the build status
[14:32:55.008] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:55.008] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:57:0) after 1m0s
[14:33:55.078] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:57:0)
[14:33:55.078] Timer tick, asking Buildbucket for the build status
[14:33:55.589] Build 8854500284594194288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:55.589] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019930346603316784:58:0) after 1m0s
[14:34:21.083] Received PubSub notification, asking Buildbucket for the build status
[14:34:21.254] Build 8854500284594194288: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[14:34:21.254] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614090856238191",
"created_by": "project:chromium",
"created_ts": "1614087516178029",
"failure_reason": "BUILD_FAILURE",
"id": "8854500284594194288",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1c8e872e61eaf5333d7ec5b583a7306497536d88\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**unit_tests** failed because of:\\n\\n- PredictionModelDownloadManagerTest.VerifiedCrxWithNoFilesShouldDeleteTempFile\"}, \"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\": \"1c8e872e61eaf5333d7ec5b583a7306497536d88\", \"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-3-xpf4\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17491, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-2\", \"got_angle_revision\": \"1a70e5a04584ce3586c9ce7243b419f0df7b052f\", \"got_dawn_revision\": \"76a94b17be3c6cfc7e88d30b90e99d7b89c8f30b\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"1c8e872e61eaf5333d7ec5b583a7306497536d88\", \"got_revision_cp\": \"refs/heads/master@{#856655}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"22186f076bf3bbe1a818792bde93b297e3773f7e\", \"got_v8_revision\": \"4120b5f2509821e8dd460b1d033708397520750a\", \"got_v8_revision_cp\": \"refs/heads/9.0.238@{#1}\", \"got_webrtc_revision\": \"e5caa9e2d3c7e402dd62892b1de6bd4be6f6aaf5\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33321}\", \"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\": \"1c8e872e61eaf5333d7ec5b583a7306497536d88\", \"swarm_hashes_refs/heads/master(at){#856655}_without_patch\": {\"absl_hardening_tests\": \"23ae563af07817f654ab4dbfb85931417dea1f0f\", \"accessibility_unittests\": \"8c4bc9782589cb3528fae62039e68402903253a5\", \"angle_unittests\": \"19e61e6c34fcf40ee4adc5ebd76a6a6b426b2dc0\", \"app_shell_unittests\": \"c8ff145966ef02bb1c619afbc6ef9af49b85487f\", \"aura_unittests\": \"7122826ccc24f80fef3adc6f47028cd45d7251bf\", \"base_unittests\": \"f2e2313390ac60afd35fe10a8d9ca4c3487eda76\", \"base_util_unittests\": \"eee751042c0ab9f25c3a09a675941c51d98647cf\", \"blink_common_unittests\": \"1c915f7ae4be130e7e9e0b9336e08910d4e115a5\", \"blink_fuzzer_unittests\": \"cb2d128860b62f4098166f7399f07934aade4fb2\", \"blink_heap_unittests\": \"a1322eaf09424d556f12cad9345c0cd1869d646c\", \"blink_platform_unittests\": \"b3f6532db06ec9ec37c3bee307962987de0f2693\", \"blink_unittests\": \"34709f83868812661b89e208393fd5056b19232c\", \"boringssl_crypto_tests\": \"ff5d956681afbb1bba991eafa3bbc846bff8a072\", \"boringssl_ssl_tests\": \"5553d20736860cc41af344a60bb8e975823fa3a3\", \"browser_switcher_bho_unittests\": \"752c1c9e704b63d403fd0df2bf9121b97f75d87e\", \"browser_tests\": \"cc2329ea6d343afd4a0168627e54d3ecacfe3329\", \"capture_unittests\": \"d4fcccd5feda38ef3f25a498581bf6bfad2b970f\", \"cast_unittests\": \"ac8a75d9b74500a919f084b27312701535467923\", \"cc_unittests\": \"bb37d614680950e47b8d4e77bc109be866d93d48\", \"chrome_app_unittests\": \"02bd564512844f2735e8b3d113ac141ccf047aa7\", \"chrome_cleaner_unittests\": \"06a48b98fc0d25186b2b340a771196a96b7c188c\", \"chrome_elf_unittests\": \"bccbe4cf2e56f2ff605a48457f8e4bcab466b3b8\", \"chromedriver_unittests\": \"10e3df357e91c45d5117ddcafabfd3c7389753f8\", \"components_browsertests\": \"37cbd51758ad282dfe9e8cf15255fc57c466c531\", \"components_unittests\": \"c6df0dfd33aa08672e1823051d3bb3e0e9e2a0bf\", \"compositor_unittests\": \"38958362f248c78f6b739386b7729d496bc012c2\", \"content_browsertests\": \"90d1797b426aa2c170017f55fd6fc98092523390\", \"content_unittests\": \"0ae0c4cc5ec87a29bea4179fb47d1ecb14ec71e8\", \"courgette_unittests\": \"04d39c416e7294e92f6aadef8b9eabb7d479694c\", \"crashpad_tests\": \"0e1da8fdd93564f326cdff7bc65614abf4e7913a\", \"cronet_tests\": \"86f1e230d87c224b54d3016726e6ea19f49c5e53\", \"cronet_unittests\": \"50e244437fe41211c93fe36df0595bb973da02f3\", \"crypto_unittests\": \"264eecd0a142dc5bce089902227d397ffc128c1c\", \"delayloads_unittests\": \"b8cf6738111586b24fa2b19e49842dc20e09022c\", \"device_unittests\": \"d152d039bdcba17e3a95034d5b2184b4e3f560ac\", \"display_unittests\": \"28f571f565d6f4361df34b42a7ff874be21221af\", \"elevation_service_unittests\": \"43b7c265b0c561aece2b5ce46635cfe9d2eee9d3\", \"events_unittests\": \"3c37b7c48a6534986125e375dcfb12502cc607fd\", \"extensions_browsertests\": \"1f6510cfc0e228e2c233dc41621fdd11c04739f2\", \"extensions_unittests\": \"2d1ac4f20150d926dc66eec5e5ab93f1c590631d\", \"filesystem_service_unittests\": \"07a492c976a40cbeff9348417c4729c919d7d170\", \"gcm_unit_tests\": \"ce630872aa1480953c7a3ce6395b8e239a3e9679\", \"gcp_unittests\": \"d51d0ed3cf964f8c0349b0309d426fd7f3637c5d\", \"gfx_unittests\": \"0b76eaedc22f537a9dade52bb3b9e0ce9fae4a78\", \"gin_unittests\": \"a4efa8f63d1797f7bb2b80dbc8c55c3e6f8e8d8e\", \"google_apis_unittests\": \"28b07bf47487c428f0880cf28b0865c63c2975d1\", \"gpu_unittests\": \"c6a705947c0acedcda93402099fdcf8f444ce83a\", \"gwp_asan_unittests\": \"6948d1baa9eaae693bfe2ca92f93c7a9771b3dd0\", \"headless_browsertests\": \"fc2207a52179b21660138a4a9945e60c2ffe29a5\", \"headless_unittests\": \"5256baf3c5d023cb092bafe902b1574aed980670\", \"install_static_unittests\": \"85f55d2826a72a2029ac4387f939519d6c98ab62\", \"installer_util_unittests\": \"1b676ae1c1f8e67316a18884359a627f3fbd25a9\", \"interactive_ui_tests\": \"e80f2be45876d689329f2485f130674fd7af1d34\", \"ipc_tests\": \"1a6c87d33da73056ff143d10d006b457ad98114e\", \"jingle_unittests\": \"f23ab189936dc187699fa6606b1deee3a763e3ee\", \"latency_unittests\": \"9c6c6c4e8890d33bd5df51091197fef95b47d69b\", \"libjingle_xmpp_unittests\": \"8e0e4b1edca3d95f58102ad277b7b246bd0ef00b\", \"liburlpattern_unittests\": \"53998a33b1a55e65cb0e67634e1841e6f98560e2\", \"media_blink_unittests\": \"0c3334eb3b1243ff985a715314b69712b51826bc\", \"media_unittests\": \"ed4c5810ebd415fbb138fabe725fb64b7cd89268\", \"message_center_unittests\": \"57f21f0c5db45c195a34527aabbf0f46677ae6f1\", \"midi_unittests\": \"bd7416c3495eaf9cc468e31275476c05e1462f5a\", \"mojo_core_unittests\": \"eb6aeff57aed32022912275ff8c7d7828609bcd3\", \"mojo_unittests\": \"444fe28fc11419b7eac58aab7b81203c8e7a87e4\", \"nacl_loader_unittests\": \"92e6512527f1d77424d3d70cdacf00037779c8c9\", \"native_theme_unittests\": \"984b72e049f94954ffd7844042131a07887f102b\", \"net_unittests\": \"289f5cccb17df04b193745bacae45b79e58c2088\", \"notification_helper_unittests\": \"2581363d7a2aedf191ee8b8b8577da3ad27ba915\", \"pdf_unittests\": \"42079f6a202d63c74960bb6fa6ba6bb1ae0d25f7\", \"perfetto_unittests\": \"5251e60168b4583e9bdab0fbfd743db6dc345521\", \"ppapi_unittests\": \"b33192c610eed27c7cf43673eccc296b865297c5\", \"printing_unittests\": \"f678872d6884afb17acd136e773c4518dd622eac\", \"remoting_unittests\": \"9313e935bcf31d7f5cdd332086b87654249b5031\", \"sbox_integration_tests\": \"cdf1c15630eb07bd6df7f95f040b3172cd7fe815\", \"sbox_unittests\": \"e4a0f3e733ab0b5c6c261923e965f5584515bd4b\", \"sbox_validation_tests\": \"be99e74323b89e10297a00ae2b7295aa75b5217c\", \"service_manager_unittests\": \"12cd754f20cfd199c1ae3bc7925bf195e6905691\", \"services_unittests\": \"ff794c88fa90b20a499b6a8a3bb5cec719d1b8a5\", \"setup_unittests\": \"76c8bdd9f340df12a5bb81a63bcfa2230d63d1da\", \"shell_dialogs_unittests\": \"e3cf73fcdc8c604fe927db22b6aeb09187356ab2\", \"skia_unittests\": \"58d7eea6186fd1e805f5fbccf6c104ef04c0e4b3\", \"snapshot_unittests\": \"6d5d789f64aec8e4656a75e0d32e9b1415f2fe4c\", \"sql_unittests\": \"2911dd30681571071e50e3e4d8ac229154f41bf9\", \"storage_unittests\": \"0c89cb20f3b23fb44deaecdf000c07fa766e1de4\", \"sync_integration_tests\": \"490fd88014d5ed086b6d5728d61a35b9a04c6f94\", \"traffic_annotation_auditor_unittests\": \"3c2a5718af1f1004c6c487bb5163e10d983125ef\", \"ui_base_unittests\": \"0f8368d221c562f760b2f9ed6bca34078472a7f1\", \"ui_touch_selection_unittests\": \"fabaae76c396e3d00fc8caab5892df266f632dd8\", \"unit_tests\": \"48d82192380bb4ac871f06feee4e42cfc48bb367\", \"updater_tests\": \"250560956a27c8af2bffc007653b9ab46e2adc77\", \"url_unittests\": \"6efdbbbf1807f7bbe94477f5bd1a4ae8af8caafb\", \"views_unittests\": \"d11bec9ab183c704cc7ebf6caa28325cd6f5b17f\", \"viz_unittests\": \"c089e71516fcf1d313fecb0ea82f7bd9f8846440\", \"vr_common_unittests\": \"ffa11a86a69d5881dc56e41dc53d691baf9b6e4d\", \"vr_pixeltests\": \"c6a64d6a850d1a95f1b65ab282bb4769b5fe6f65\", \"weblayer_browsertests\": \"691b3087e951b9277505a3c75a7210a5e73b8d40\", \"weblayer_unittests\": \"2b8db55d0864b192bd9a3119f748c041ca98cacf\", \"wm_unittests\": \"7ff32ae036bbf47bb86ff5aede89c795590b5ee2\", \"wtf_unittests\": \"1319673a932196fd85b42f4ba7954a7678262f27\", \"zlib_unittests\": \"a8ca5e0a4edfd7b2ba36a3eb4d6c40d626aba844\", \"zucchini_unittests\": \"203fbc1ba79e727c6d992d3ea1e8c78a38125eb5\"}}, \"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-3-xpf4\"], \"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\": [\"5706-397711c\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**unit_tests** failed because of:\\n\\n- PredictionModelDownloadManagerTest.VerifiedCrxWithNoFilesShouldDeleteTempFile\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1614087522236691",
"status": "COMPLETED",
"status_changed_ts": "1614087522544785",
"tags": [
"build_address:luci.chromium.ci/win-asan/17491",
"builder:win-asan",
"buildset:commit/git/1c8e872e61eaf5333d7ec5b583a7306497536d88",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1c8e872e61eaf5333d7ec5b583a7306497536d88",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019930346603316784",
"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/8854500284594194288/+/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:51e7ee7d95600710",
"user_agent:luci-scheduler"
],
"updated_ts": "1614090857318162",
"url": "https://ci.chromium.org/b/8854500284594194288",
"utcnow_ts": "1614090861244989"
}
}
[14:34:21.254] Invocation finished in 55m46.374556496s with status FAILED