[01:01:20.312] New invocation is queued and will start shortly
[01:01:21.399] Starting the invocation (attempt 1)
[01:01:21.434] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:01:21.434] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:01:21.434] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9024236045636621792",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"3563e39fc298234829e6d88302d3116a29654454\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9024236045636621792",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/3563e39fc298234829e6d88302d3116a29654454",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3563e39fc298234829e6d88302d3116a29654454",
"gitiles_ref:refs/heads/master"
]
}
[01:01:24.421] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1609981282193690",
"id": "8858805983000986176",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3563e39fc298234829e6d88302d3116a29654454\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1609981283832363",
"tags": [
"build_address:luci.chromium.ci/win-asan/16663",
"builder:win-asan",
"buildset:commit/git/3563e39fc298234829e6d88302d3116a29654454",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3563e39fc298234829e6d88302d3116a29654454",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024236045636621792",
"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/8858805983000986176/+/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": "1609981283832481",
"url": "https://ci.chromium.org/b/8858805983000986176",
"utcnow_ts": "1609981284344358"
}
}
[01:01:24.421] Task URL: https://ci.chromium.org/b/8858805983000986176
[01:01:24.421] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:2:0) after 1m0s
[01:01:28.557] Received PubSub notification, asking Buildbucket for the build status
[01:01:28.930] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:24.708] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:2:0)
[01:02:24.708] Timer tick, asking Buildbucket for the build status
[01:02:24.823] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:24.823] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:4:0) after 1m0s
[01:03:24.863] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:4:0)
[01:03:24.863] Timer tick, asking Buildbucket for the build status
[01:03:25.190] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:25.190] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:5:0) after 1m0s
[01:04:25.246] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:5:0)
[01:04:25.246] Timer tick, asking Buildbucket for the build status
[01:04:25.344] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:25.344] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:6:0) after 1m0s
[01:05:25.369] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:6:0)
[01:05:25.369] Timer tick, asking Buildbucket for the build status
[01:05:25.482] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:25.482] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:7:0) after 1m0s
[01:06:25.604] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:7:0)
[01:06:25.604] Timer tick, asking Buildbucket for the build status
[01:06:25.799] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:25.799] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:8:0) after 1m0s
[01:07:25.830] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:8:0)
[01:07:25.830] Timer tick, asking Buildbucket for the build status
[01:07:25.894] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:25.894] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:9:0) after 1m0s
[01:08:26.115] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:9:0)
[01:08:26.115] Timer tick, asking Buildbucket for the build status
[01:08:26.404] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:26.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:10:0) after 1m0s
[01:09:26.510] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:10:0)
[01:09:26.510] Timer tick, asking Buildbucket for the build status
[01:09:26.581] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:26.581] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:11:0) after 1m0s
[01:10:26.685] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:11:0)
[01:10:26.685] Timer tick, asking Buildbucket for the build status
[01:10:26.770] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:26.770] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:12:0) after 1m0s
[01:11:26.850] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:12:0)
[01:11:26.850] Timer tick, asking Buildbucket for the build status
[01:11:26.983] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:26.983] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:13:0) after 1m0s
[01:12:26.989] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:13:0)
[01:12:26.989] Timer tick, asking Buildbucket for the build status
[01:12:27.134] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:27.134] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:14:0) after 1m0s
[01:13:27.222] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:14:0)
[01:13:27.222] Timer tick, asking Buildbucket for the build status
[01:13:27.352] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:27.352] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:15:0) after 1m0s
[01:14:27.424] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:15:0)
[01:14:27.424] Timer tick, asking Buildbucket for the build status
[01:14:27.598] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:27.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:16:0) after 1m0s
[01:15:27.639] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:16:0)
[01:15:27.639] Timer tick, asking Buildbucket for the build status
[01:15:27.858] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:27.858] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:17:0) after 1m0s
[01:16:27.900] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:17:0)
[01:16:27.900] Timer tick, asking Buildbucket for the build status
[01:16:28.489] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:28.489] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:18:0) after 1m0s
[01:17:28.520] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:18:0)
[01:17:28.520] Timer tick, asking Buildbucket for the build status
[01:17:28.670] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:28.670] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:19:0) after 1m0s
[01:18:28.718] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:19:0)
[01:18:28.718] Timer tick, asking Buildbucket for the build status
[01:18:28.847] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:28.847] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:20:0) after 1m0s
[01:19:28.883] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:20:0)
[01:19:28.883] Timer tick, asking Buildbucket for the build status
[01:19:29.061] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:29.061] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:21:0) after 1m0s
[01:20:29.062] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:21:0)
[01:20:29.062] Timer tick, asking Buildbucket for the build status
[01:20:29.520] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:29.520] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:22:0) after 1m0s
[01:21:29.545] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:22:0)
[01:21:29.545] Timer tick, asking Buildbucket for the build status
[01:21:29.674] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:29.674] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:23:0) after 1m0s
[01:22:29.712] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:23:0)
[01:22:29.712] Timer tick, asking Buildbucket for the build status
[01:22:29.890] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:29.890] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:24:0) after 1m0s
[01:23:29.949] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:24:0)
[01:23:29.950] Timer tick, asking Buildbucket for the build status
[01:23:30.152] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:30.152] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:25:0) after 1m0s
[01:24:30.260] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:25:0)
[01:24:30.260] Timer tick, asking Buildbucket for the build status
[01:24:30.445] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:30.445] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:26:0) after 1m0s
[01:25:30.600] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:26:0)
[01:25:30.600] Timer tick, asking Buildbucket for the build status
[01:25:30.744] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:30.744] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:27:0) after 1m0s
[01:26:30.907] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:27:0)
[01:26:30.907] Timer tick, asking Buildbucket for the build status
[01:26:31.204] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:31.205] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:28:0) after 1m0s
[01:27:31.252] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:28:0)
[01:27:31.252] Timer tick, asking Buildbucket for the build status
[01:27:31.332] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:31.332] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:29:0) after 1m0s
[01:28:31.441] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:29:0)
[01:28:31.441] Timer tick, asking Buildbucket for the build status
[01:28:31.586] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:31.586] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:30:0) after 1m0s
[01:29:31.699] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:30:0)
[01:29:31.699] Timer tick, asking Buildbucket for the build status
[01:29:34.334] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:34.334] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:31:0) after 1m0s
[01:30:34.409] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:31:0)
[01:30:34.409] Timer tick, asking Buildbucket for the build status
[01:30:34.492] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:34.492] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:32:0) after 1m0s
[01:31:34.542] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:32:0)
[01:31:34.542] Timer tick, asking Buildbucket for the build status
[01:31:35.064] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:35.064] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:33:0) after 1m0s
[01:32:35.068] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:33:0)
[01:32:35.068] Timer tick, asking Buildbucket for the build status
[01:32:35.428] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:35.428] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:34:0) after 1m0s
[01:33:35.499] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:34:0)
[01:33:35.499] Timer tick, asking Buildbucket for the build status
[01:33:35.811] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:35.811] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:35:0) after 1m0s
[01:34:35.851] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:35:0)
[01:34:35.851] Timer tick, asking Buildbucket for the build status
[01:34:35.932] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:35.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:36:0) after 1m0s
[01:35:35.938] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:36:0)
[01:35:35.938] Timer tick, asking Buildbucket for the build status
[01:35:36.055] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:36.055] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:37:0) after 1m0s
[01:36:36.212] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:37:0)
[01:36:36.212] Timer tick, asking Buildbucket for the build status
[01:36:36.340] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:36.340] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:38:0) after 1m0s
[01:37:36.539] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:38:0)
[01:37:36.539] Timer tick, asking Buildbucket for the build status
[01:37:36.617] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:36.617] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:39:0) after 1m0s
[01:38:36.764] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:39:0)
[01:38:36.764] Timer tick, asking Buildbucket for the build status
[01:38:36.855] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:36.855] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:40:0) after 1m0s
[01:39:37.033] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:40:0)
[01:39:37.033] Timer tick, asking Buildbucket for the build status
[01:39:37.202] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:37.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:41:0) after 1m0s
[01:40:37.480] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:41:0)
[01:40:37.480] Timer tick, asking Buildbucket for the build status
[01:40:37.648] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:37.648] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:42:0) after 1m0s
[01:41:37.721] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:42:0)
[01:41:37.721] Timer tick, asking Buildbucket for the build status
[01:41:38.033] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:38.033] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:43:0) after 1m0s
[01:42:38.144] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:43:0)
[01:42:38.144] Timer tick, asking Buildbucket for the build status
[01:42:39.002] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:39.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:44:0) after 1m0s
[01:43:39.147] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:44:0)
[01:43:39.147] Timer tick, asking Buildbucket for the build status
[01:43:39.297] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:39.297] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:45:0) after 1m0s
[01:44:39.413] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:45:0)
[01:44:39.413] Timer tick, asking Buildbucket for the build status
[01:44:39.743] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:39.743] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:46:0) after 1m0s
[01:45:40.298] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:46:0)
[01:45:40.298] Timer tick, asking Buildbucket for the build status
[01:45:40.392] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:40.392] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:47:0) after 1m0s
[01:46:40.560] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:47:0)
[01:46:40.560] Timer tick, asking Buildbucket for the build status
[01:46:40.859] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:40.859] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:48:0) after 1m0s
[01:47:41.017] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:48:0)
[01:47:41.017] Timer tick, asking Buildbucket for the build status
[01:47:41.201] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:41.201] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:49:0) after 1m0s
[01:48:41.295] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:49:0)
[01:48:41.295] Timer tick, asking Buildbucket for the build status
[01:48:41.704] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:41.704] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:50:0) after 1m0s
[01:49:41.876] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:50:0)
[01:49:41.876] Timer tick, asking Buildbucket for the build status
[01:49:42.078] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:42.078] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:51:0) after 1m0s
[01:50:42.364] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:51:0)
[01:50:42.364] Timer tick, asking Buildbucket for the build status
[01:50:42.500] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:42.500] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:52:0) after 1m0s
[01:51:42.921] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:52:0)
[01:51:42.921] Timer tick, asking Buildbucket for the build status
[01:51:43.078] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:43.078] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:53:0) after 1m0s
[01:52:43.162] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:53:0)
[01:52:43.162] Timer tick, asking Buildbucket for the build status
[01:52:43.301] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:43.301] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:54:0) after 1m0s
[01:53:43.383] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:54:0)
[01:53:43.383] Timer tick, asking Buildbucket for the build status
[01:53:43.696] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:43.696] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:55:0) after 1m0s
[01:54:43.878] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:55:0)
[01:54:43.878] Timer tick, asking Buildbucket for the build status
[01:54:44.191] Build 8858805983000986176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:44.191] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024236045636621792:56:0) after 1m0s
[01:55:21.599] Received PubSub notification, asking Buildbucket for the build status
[01:55:21.693] Build 8858805983000986176: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[01:55:21.693] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1609984520754054",
"created_by": "project:chromium",
"created_ts": "1609981282193690",
"failure_reason": "BUILD_FAILURE",
"id": "8858805983000986176",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3563e39fc298234829e6d88302d3116a29654454\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- DiceWebSigninInterceptorBrowserTest.InterceptionTest\"}, \"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\": \"3563e39fc298234829e6d88302d3116a29654454\", \"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-1-juf7\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16663, \"clang_revision\": \"llvmorg-12-init-15676-g5c951623-1\", \"got_angle_revision\": \"dc2cf6fc370e1a620fadb631683fd2bf6786672f\", \"got_dawn_revision\": \"f1fa60b91230c9f219272c7c5b26fa2aaa66a758\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"3563e39fc298234829e6d88302d3116a29654454\", \"got_revision_cp\": \"refs/heads/master@{#840850}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"b9e179f1f239bd1da8fffbd997a41095345d1f6a\", \"got_v8_revision\": \"8bf25fa0cda754dd0f8e0f8b7c9f8854eabfe72d\", \"got_v8_revision_cp\": \"refs/heads/8.9.213@{#1}\", \"got_webrtc_revision\": \"fc6b87192db6d5e4fd6997814f00b6792cf16180\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32904}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.win:win-asan\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3563e39fc298234829e6d88302d3116a29654454\", \"swarm_hashes_refs/heads/master(at){#840850}_without_patch\": {\"absl_hardening_tests\": \"6c63c3b2a99f0dab4c9d1998d496e0d36fd5d00c\", \"accessibility_unittests\": \"86489aa680fa523b8d65336954d1a1b061ae2d6e\", \"angle_unittests\": \"fa6d2a342e89c703f040c41d5bb271c857d8ad54\", \"app_shell_unittests\": \"c313669f9ab41674c1f12a760b7acd02122d9e7e\", \"aura_unittests\": \"82a9b980020005780e8954501a8512cf0aa46fae\", \"base_unittests\": \"01c7331b88c1e53d54d32fce74ce436c134bd94f\", \"base_util_unittests\": \"1db728ae4e3deb48921f55b04091dabbf36b7f68\", \"blink_common_unittests\": \"ee12fb6e6971f03cae7aa5f2867109b5fb4a7905\", \"blink_fuzzer_unittests\": \"559cfecc5f5ff94f989728cd2d43c96e66ecf7b1\", \"blink_heap_unittests\": \"a764cb625115ee7bf8a9b372830e768a813b4cfd\", \"blink_platform_unittests\": \"2dd68f7b6f7a6a39a2997b8c2d6bae2293f64549\", \"blink_unittests\": \"39894544bbaaa3946d3935f78e045f10d4f688e7\", \"boringssl_crypto_tests\": \"2a744a803e84fbbe963af661e87ec3fc47d43846\", \"boringssl_ssl_tests\": \"15c0f261e75eaec3f84fa782e8d27b6c5ef5c279\", \"browser_switcher_bho_unittests\": \"6172f3c73c09ff4122d690f9c7be58fe8094035f\", \"browser_tests\": \"337092e8f582160872b533053d5653a604f7f611\", \"capture_unittests\": \"09f47babec2ea1c050685b7b9e3d8d9f9fcaaf02\", \"cast_unittests\": \"9fb5bfc9df098549007143593be281fc9606319b\", \"cc_unittests\": \"22fd2b3c79c62d818db1fd913427143734c3999d\", \"chrome_app_unittests\": \"77e6f8ca609da6fa80b861b91da7f5b6db1255a6\", \"chrome_cleaner_unittests\": \"bea2c400a4f8c6d5b2139e93f8e0fc13864c6403\", \"chrome_elf_unittests\": \"acfd66f97b70c88609b760fe990379b4b840164a\", \"chromedriver_unittests\": \"281bd675b47372783e113cd0a2b1969c52f3a06c\", \"components_browsertests\": \"e4ddecc51ef15b7bcfe25375b7502112ee8faa27\", \"components_unittests\": \"f09a0d128fb92807bf1a3dd8c5f19c383073bd7a\", \"compositor_unittests\": \"b2bc6a1795797aef7d78285bedc08f5e7588c39d\", \"content_browsertests\": \"442cae0d78bba9a999b4db849ebcd80de81f4fba\", \"content_unittests\": \"7b36d94cc018ecc129c622d7b75d991a7162607e\", \"courgette_unittests\": \"adde1bbd65e40f790cd72109145b121fa7edaeba\", \"crashpad_tests\": \"09a29c4885f59e988b50557bf40928bb964465b8\", \"cronet_tests\": \"fafa0d4651f5960e294fb535ac2ee5326a6ba426\", \"cronet_unittests\": \"4df26732586bc5076869737c542d08e681e0b716\", \"crypto_unittests\": \"261c7a98f52367684bc9849d31702436c1cd9b1f\", \"delayloads_unittests\": \"38de3433e5309b83569d5e4eeed7ffab615b23fb\", \"device_unittests\": \"6c87f30f49d9f9726d13c67a4755750f1fb89c2f\", \"display_unittests\": \"3b8f1756ce08521e633316ba3c97a0d9d056f0a6\", \"elevation_service_unittests\": \"829103cc4080edc63ac767aef32b8a1d8f61b75f\", \"events_unittests\": \"a0b6917888cf397a82226fd11178f84cefb5c8d5\", \"extensions_browsertests\": \"fcb68e88308fcd431e2ef7fb85bb11f398aab231\", \"extensions_unittests\": \"4ed3cfa2879d92250c6118458c3b964a1a2bd8bb\", \"filesystem_service_unittests\": \"c4a5cd888f1e7b4c3f6f8031b2fb89383151df4f\", \"gcm_unit_tests\": \"6c48361f766467793af484e422eae6961bb2fb0b\", \"gcp_unittests\": \"2b9f7fea0598613a23e8017a528d5a90c3658a6c\", \"gfx_unittests\": \"87f763032ce2095f9b88ecb41c66d2e1be2497c2\", \"gin_unittests\": \"8135f2c3a5cc5056f07c8eb73cdd09a12b80f303\", \"google_apis_unittests\": \"649de009da4aeaabc033536d930658868eb80a02\", \"gpu_unittests\": \"8d62a825d4c7b4d09fd08a69404b0666fdbde172\", \"gwp_asan_unittests\": \"7482c7b5419e2cf5c8d3e82a692028641595f68f\", \"headless_browsertests\": \"00f892dcd6d4f2b1dc4bb64191d211e6385e79e1\", \"headless_unittests\": \"9b7d00ac7eb888d8f75ecd9b9fa6dacaea44050d\", \"install_static_unittests\": \"303a0d902c9f62bfdb1debdfe8a4394ed1080e0e\", \"installer_util_unittests\": \"a5ecb69fed71f8d1f210965218c6479eba273a91\", \"interactive_ui_tests\": \"b68f56e606d0d60ef8ba3498f27a7febde24bb21\", \"ipc_tests\": \"93631baba2343cf486a0b40db690d936fbc2837a\", \"jingle_unittests\": \"92208e81d5cd09a7d9f4afea752c5e65a64b9ecf\", \"latency_unittests\": \"616854a0fa4da0bf60dde0404a392f4ed3bfceb3\", \"libjingle_xmpp_unittests\": \"08e3c9ffaa8a0b82bae92829bf13db25a945a7ef\", \"liburlpattern_unittests\": \"1bc7c1f7c9554e4635a3777750c011ece6f332e7\", \"media_blink_unittests\": \"89b1962e882193e494ec276fd18bcd311785f433\", \"media_unittests\": \"05c6cb8a69cf9f64a89ea642bb47185161aecaec\", \"message_center_unittests\": \"3c66549a88528bd26a0b34ba54aa79829ae7a334\", \"midi_unittests\": \"5d65e66ac0f6b2deb77054dffb29510356cf1b2e\", \"mojo_core_unittests\": \"1f09e40b3f10dda5e1e569c18ee1cfda75bff3af\", \"mojo_unittests\": \"c636fb395063954ffc74a7dc66510506d1854947\", \"nacl_loader_unittests\": \"c9132a376bcca964b95ee437965b173a2b2b0495\", \"native_theme_unittests\": \"887c88b4a95192912465f5094286f97e1858125d\", \"net_unittests\": \"13e33d69702359bd99a434f986248999f110bdd7\", \"notification_helper_unittests\": \"4860198ee64e401146aff727d2a96b287e694fdc\", \"pdf_unittests\": \"7276bbada71959db15d5f448d7e592293606b3a3\", \"perfetto_unittests\": \"101a64de820530ab7b6a96360210d23efcbc4e1e\", \"ppapi_unittests\": \"7886e12e31c5abe03fca6b2f21fefc0441d588b8\", \"printing_unittests\": \"d3e47020689f735f3f10ac844f3d0505e32360a4\", \"remoting_unittests\": \"1c06a1ebfad4ce1d480998e52b96503c04bef259\", \"sbox_integration_tests\": \"a055120c987acc9ede2982c93cdf9003d35bb61e\", \"sbox_unittests\": \"50183fbfa2e1238e33e9c0fa4e90178ceea32658\", \"sbox_validation_tests\": \"3a420e4a13a5f1051577a1938f7205b0c6532736\", \"service_manager_unittests\": \"69c68f81da591a653aac2038b3fd7edd82c8bd7b\", \"services_unittests\": \"315f5535b9c4af6f2b7eeaf1ccd3641ccd57fbff\", \"setup_unittests\": \"e34dc2d875ebb415fe909da083aa9e3b2459f782\", \"shell_dialogs_unittests\": \"6697efe4148c01e795d17b901b8f32b1d735beaf\", \"skia_unittests\": \"1e4c0123446036a46676a15467f8074544a1ede1\", \"snapshot_unittests\": \"0f1485ef77930df2c1a5cead936a9d169720da27\", \"sql_unittests\": \"f61dd24b1755390b5a226f8f020139b249134db8\", \"storage_unittests\": \"753c9dec796c19973656a500570910890429afd6\", \"sync_integration_tests\": \"75f29dd4efafbb2a0b1b776070bbf26dd47a5ae8\", \"traffic_annotation_auditor_unittests\": \"a7910e492d3052ca33a0a85189aaf1a008da4cc9\", \"ui_base_unittests\": \"a2752f584e183327dc9cb4c1c94a7d8dea85305b\", \"ui_touch_selection_unittests\": \"17be6ebe3cdcfb76074c17773f9a60d73798fa86\", \"unit_tests\": \"c87cf7c8f414f504e9105a5e19ec3934be5702df\", \"updater_tests\": \"d1fc16c0977c62652f88fb8b7a682090ca4b4b6b\", \"url_unittests\": \"faa0692ff927bd734dba30b6e7405bbeadf4ffe8\", \"views_unittests\": \"0c12fe77b7fed28513ccecabbdd205fe4aad49d8\", \"viz_unittests\": \"4c547b98096016f59ee5d0194048cfa73785b1bc\", \"vr_common_unittests\": \"3ec648ed567515d7026ae9cfad2f10fb94a0494b\", \"vr_pixeltests\": \"38476e77b2eb840008892b574c997507f887f8a9\", \"weblayer_browsertests\": \"917d74e604891bf70cc7fb25a08b5a2cd06e60a5\", \"weblayer_unittests\": \"03d6f149f2f266839d04257e409bb5c5d79964ad\", \"wm_unittests\": \"53103057b12e6f18dce00b4de52f76e397695cd3\", \"wtf_unittests\": \"c3f4b5e2a1b384b9d914967c27bf83be425e4a00\", \"zlib_unittests\": \"a290191908b70a51a954f7730ea204b1f3a4d2b3\", \"zucchini_unittests\": \"ce909570eb5e8d7c492cabb979d8762a76732a27\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_a4ee364c4b5996d1649f16c77d7186222b75eb5b75762fbbe3baeec97aac12d9_v2\", \"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-1-juf7\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5657-0417a3f\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- DiceWebSigninInterceptorBrowserTest.InterceptionTest\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1609981287432815",
"status": "COMPLETED",
"status_changed_ts": "1609981288288315",
"tags": [
"build_address:luci.chromium.ci/win-asan/16663",
"builder:win-asan",
"buildset:commit/git/3563e39fc298234829e6d88302d3116a29654454",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3563e39fc298234829e6d88302d3116a29654454",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024236045636621792",
"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/8858805983000986176/+/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:50f32e58d9477f10",
"user_agent:luci-scheduler"
],
"updated_ts": "1609984521154278",
"url": "https://ci.chromium.org/b/8858805983000986176",
"utcnow_ts": "1609984521683608"
}
}
[01:55:21.693] Invocation finished in 54m1.39652283s with status FAILED