[07:38:55.325] New invocation is queued and will start shortly
[07:38:56.463] Starting the invocation (attempt 1)
[07:38:56.507] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:38:56.507] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:38:56.507] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022942674316127680",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"9d3c4a91f6c2cda7853beca707291e758da4ef44\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9022942674316127680",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/9d3c4a91f6c2cda7853beca707291e758da4ef44",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9d3c4a91f6c2cda7853beca707291e758da4ef44",
"gitiles_ref:refs/heads/master"
]
}
[07:38:57.134] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611214736653425",
"id": "8857512612257588896",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9d3c4a91f6c2cda7853beca707291e758da4ef44\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611214737019726",
"tags": [
"build_address:luci.chromium.ci/win-asan/16926",
"builder:win-asan",
"buildset:commit/git/9d3c4a91f6c2cda7853beca707291e758da4ef44",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9d3c4a91f6c2cda7853beca707291e758da4ef44",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022942674316127680",
"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/8857512612257588896/+/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": "1611214737019910",
"url": "https://ci.chromium.org/b/8857512612257588896",
"utcnow_ts": "1611214737127167"
}
}
[07:38:57.134] Task URL: https://ci.chromium.org/b/8857512612257588896
[07:38:57.134] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:2:0) after 1m0s
[07:39:03.958] Received PubSub notification, asking Buildbucket for the build status
[07:39:04.037] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:57.190] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:2:0)
[07:39:57.191] Timer tick, asking Buildbucket for the build status
[07:39:57.317] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:57.317] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:4:0) after 1m0s
[07:40:57.342] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:4:0)
[07:40:57.342] Timer tick, asking Buildbucket for the build status
[07:40:57.458] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:57.458] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:5:0) after 1m0s
[07:41:57.528] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:5:0)
[07:41:57.528] Timer tick, asking Buildbucket for the build status
[07:41:57.730] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:57.730] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:6:0) after 1m0s
[07:42:57.843] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:6:0)
[07:42:57.843] Timer tick, asking Buildbucket for the build status
[07:42:57.971] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:57.971] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:7:0) after 1m0s
[07:43:58.124] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:7:0)
[07:43:58.124] Timer tick, asking Buildbucket for the build status
[07:43:58.228] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:58.228] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:8:0) after 1m0s
[07:44:58.299] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:8:0)
[07:44:58.299] Timer tick, asking Buildbucket for the build status
[07:44:58.413] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:58.413] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:9:0) after 1m0s
[07:45:58.478] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:9:0)
[07:45:58.478] Timer tick, asking Buildbucket for the build status
[07:45:58.617] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:58.617] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:10:0) after 1m0s
[07:46:58.648] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:10:0)
[07:46:58.648] Timer tick, asking Buildbucket for the build status
[07:46:58.901] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:58.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:11:0) after 1m0s
[07:47:58.877] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:11:0)
[07:47:58.877] Timer tick, asking Buildbucket for the build status
[07:47:58.985] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:58.985] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:12:0) after 1m0s
[07:48:58.973] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:12:0)
[07:48:58.973] Timer tick, asking Buildbucket for the build status
[07:48:59.082] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:59.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:13:0) after 1m0s
[07:49:59.124] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:13:0)
[07:49:59.124] Timer tick, asking Buildbucket for the build status
[07:49:59.501] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:59.501] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:14:0) after 1m0s
[07:50:59.617] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:14:0)
[07:50:59.617] Timer tick, asking Buildbucket for the build status
[07:51:00.170] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:00.170] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:15:0) after 1m0s
[07:52:00.237] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:15:0)
[07:52:00.237] Timer tick, asking Buildbucket for the build status
[07:52:00.433] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:00.433] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:16:0) after 1m0s
[07:53:00.453] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:16:0)
[07:53:00.453] Timer tick, asking Buildbucket for the build status
[07:53:00.631] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:00.631] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:17:0) after 1m0s
[07:54:00.848] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:17:0)
[07:54:00.848] Timer tick, asking Buildbucket for the build status
[07:54:01.115] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:01.115] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:18:0) after 1m0s
[07:55:01.176] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:18:0)
[07:55:01.176] Timer tick, asking Buildbucket for the build status
[07:55:01.315] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:01.315] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:19:0) after 1m0s
[07:56:01.329] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:19:0)
[07:56:01.329] Timer tick, asking Buildbucket for the build status
[07:56:01.728] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:01.728] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:20:0) after 1m0s
[07:57:01.756] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:20:0)
[07:57:01.756] Timer tick, asking Buildbucket for the build status
[07:57:01.918] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:01.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:21:0) after 1m0s
[07:58:01.994] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:21:0)
[07:58:01.994] Timer tick, asking Buildbucket for the build status
[07:58:02.186] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:02.186] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:22:0) after 1m0s
[07:59:02.209] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:22:0)
[07:59:02.209] Timer tick, asking Buildbucket for the build status
[07:59:02.336] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:02.336] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:23:0) after 1m0s
[08:00:02.653] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:23:0)
[08:00:02.653] Timer tick, asking Buildbucket for the build status
[08:00:02.802] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:02.802] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:24:0) after 1m0s
[08:01:02.958] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:24:0)
[08:01:02.958] Timer tick, asking Buildbucket for the build status
[08:01:03.116] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:03.116] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:25:0) after 1m0s
[08:02:03.128] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:25:0)
[08:02:03.128] Timer tick, asking Buildbucket for the build status
[08:02:03.537] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:03.537] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:26:0) after 1m0s
[08:03:03.654] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:26:0)
[08:03:03.654] Timer tick, asking Buildbucket for the build status
[08:03:03.742] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:03.742] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:27:0) after 1m0s
[08:04:03.763] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:27:0)
[08:04:03.763] Timer tick, asking Buildbucket for the build status
[08:04:04.085] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:04.085] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:28:0) after 1m0s
[08:05:04.122] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:28:0)
[08:05:04.122] Timer tick, asking Buildbucket for the build status
[08:05:04.508] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:04.508] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:29:0) after 1m0s
[08:06:04.538] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:29:0)
[08:06:04.538] Timer tick, asking Buildbucket for the build status
[08:06:04.625] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:04.625] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:30:0) after 1m0s
[08:07:04.778] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:30:0)
[08:07:04.778] Timer tick, asking Buildbucket for the build status
[08:07:04.961] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:04.961] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:31:0) after 1m0s
[08:08:05.176] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:31:0)
[08:08:05.176] Timer tick, asking Buildbucket for the build status
[08:08:05.296] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:05.296] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:32:0) after 1m0s
[08:09:05.378] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:32:0)
[08:09:05.378] Timer tick, asking Buildbucket for the build status
[08:09:05.588] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:05.588] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:33:0) after 1m0s
[08:10:05.619] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:33:0)
[08:10:05.619] Timer tick, asking Buildbucket for the build status
[08:10:05.802] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:05.802] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:34:0) after 1m0s
[08:11:05.856] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:34:0)
[08:11:05.856] Timer tick, asking Buildbucket for the build status
[08:11:05.943] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:05.943] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:35:0) after 1m0s
[08:12:05.995] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:35:0)
[08:12:05.995] Timer tick, asking Buildbucket for the build status
[08:12:06.096] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:06.096] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:36:0) after 1m0s
[08:13:06.123] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:36:0)
[08:13:06.123] Timer tick, asking Buildbucket for the build status
[08:13:06.310] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:06.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:37:0) after 1m0s
[08:14:06.314] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:37:0)
[08:14:06.314] Timer tick, asking Buildbucket for the build status
[08:14:06.830] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:06.830] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:38:0) after 1m0s
[08:15:06.904] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:38:0)
[08:15:06.904] Timer tick, asking Buildbucket for the build status
[08:15:07.004] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:07.004] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:39:0) after 1m0s
[08:16:07.089] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:39:0)
[08:16:07.089] Timer tick, asking Buildbucket for the build status
[08:16:07.235] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:07.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:40:0) after 1m0s
[08:17:07.323] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:40:0)
[08:17:07.323] Timer tick, asking Buildbucket for the build status
[08:17:07.432] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:07.432] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:41:0) after 1m0s
[08:18:07.458] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:41:0)
[08:18:07.458] Timer tick, asking Buildbucket for the build status
[08:18:07.799] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:07.799] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:42:0) after 1m0s
[08:19:07.873] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:42:0)
[08:19:07.873] Timer tick, asking Buildbucket for the build status
[08:19:08.443] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:08.443] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:43:0) after 1m0s
[08:20:08.473] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:43:0)
[08:20:08.473] Timer tick, asking Buildbucket for the build status
[08:20:08.589] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:08.589] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:44:0) after 1m0s
[08:21:08.620] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:44:0)
[08:21:08.620] Timer tick, asking Buildbucket for the build status
[08:21:08.711] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:08.712] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:45:0) after 1m0s
[08:22:08.734] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:45:0)
[08:22:08.734] Timer tick, asking Buildbucket for the build status
[08:22:08.813] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:08.813] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:46:0) after 1m0s
[08:23:08.849] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:46:0)
[08:23:08.849] Timer tick, asking Buildbucket for the build status
[08:23:09.499] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:09.499] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:47:0) after 1m0s
[08:24:09.558] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:47:0)
[08:24:09.558] Timer tick, asking Buildbucket for the build status
[08:24:09.980] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:09.980] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:48:0) after 1m0s
[08:25:10.133] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:48:0)
[08:25:10.133] Timer tick, asking Buildbucket for the build status
[08:25:10.238] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:10.238] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:49:0) after 1m0s
[08:26:10.324] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:49:0)
[08:26:10.324] Timer tick, asking Buildbucket for the build status
[08:26:10.471] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:10.471] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:50:0) after 1m0s
[08:27:10.503] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:50:0)
[08:27:10.503] Timer tick, asking Buildbucket for the build status
[08:27:11.153] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:11.153] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:51:0) after 1m0s
[08:28:11.136] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:51:0)
[08:28:11.136] Timer tick, asking Buildbucket for the build status
[08:28:11.574] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:11.574] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:52:0) after 1m0s
[08:29:11.606] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:52:0)
[08:29:11.606] Timer tick, asking Buildbucket for the build status
[08:29:11.904] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:11.904] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:53:0) after 1m0s
[08:30:11.967] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:53:0)
[08:30:11.967] Timer tick, asking Buildbucket for the build status
[08:30:12.102] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:12.102] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:54:0) after 1m0s
[08:31:12.134] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:54:0)
[08:31:12.134] Timer tick, asking Buildbucket for the build status
[08:31:12.215] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:12.215] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:55:0) after 1m0s
[08:32:12.239] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:55:0)
[08:32:12.239] Timer tick, asking Buildbucket for the build status
[08:32:12.754] Build 8857512612257588896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:12.754] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022942674316127680:56:0) after 1m0s
[08:32:38.530] Received PubSub notification, asking Buildbucket for the build status
[08:32:38.625] Build 8857512612257588896: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[08:32:38.625] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611217956000693",
"created_by": "project:chromium",
"created_ts": "1611214736653425",
"id": "8857512612257588896",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9d3c4a91f6c2cda7853beca707291e758da4ef44\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"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\": \"9d3c4a91f6c2cda7853beca707291e758da4ef44\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-us-central1-b-32-0-gbsn\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16926, \"clang_revision\": \"llvmorg-12-init-16548-gf1d5cbbd-1\", \"got_angle_revision\": \"137271ffc54d5c23cc96e7d5624c3f8648f77091\", \"got_dawn_revision\": \"762814bd922932d3d9da9b220463b6ce5f167d76\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"9d3c4a91f6c2cda7853beca707291e758da4ef44\", \"got_revision_cp\": \"refs/heads/master@{#845553}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"1d5174b9dc3be500d63daf227b163b5e86bbc7b5\", \"got_v8_revision\": \"ed78a10326a2854dcf8561c61c974270f8e01dbe\", \"got_v8_revision_cp\": \"refs/heads/9.0.34@{#1}\", \"got_webrtc_revision\": \"49b20f919abe9a21227b0f6f86d223a3fb1e5ee4\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33048}\", \"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\": \"9d3c4a91f6c2cda7853beca707291e758da4ef44\", \"swarm_hashes_refs/heads/master(at){#845553}_without_patch\": {\"absl_hardening_tests\": \"dc35cb42963efcea80f33ed5c78fd03aed79fcb6\", \"accessibility_unittests\": \"b4e4a3da289f55776780d8a193e071b426ea07a1\", \"angle_unittests\": \"50395dbfab2eadbd94b2007aeb70f3c57fd76301\", \"app_shell_unittests\": \"be762bcfd7b2bbd64444787cef64d15ee4e0f4d1\", \"aura_unittests\": \"d9dde5ad8f2342dbf6d9296afe356e2c3b5c9c72\", \"base_unittests\": \"17cf1e6082693145e29d58c8224f1bd42cb64c98\", \"base_util_unittests\": \"f9ad5889cc13f826ef49367cd1efeb2a3cae4d6f\", \"blink_common_unittests\": \"ab7328556b3596bf07e4bb238f4c46dde8ce2a63\", \"blink_fuzzer_unittests\": \"463389ffee23d2f021801f07c2079481e7e20d41\", \"blink_heap_unittests\": \"69c65c5689ed234729725761433c8353019ed5e9\", \"blink_platform_unittests\": \"6650b9176a85b0c8a98a33258a761956389dfd79\", \"blink_unittests\": \"f6181167c7c371a5dd51977f6caf27fecbee5b5d\", \"boringssl_crypto_tests\": \"11c5024930a6fae2479d268bf8604e4fab4dc188\", \"boringssl_ssl_tests\": \"298fd77075a2fe073948e92c10bba7904588c176\", \"browser_switcher_bho_unittests\": \"cba2ae6ca042740a44508802432b5e656df7bb18\", \"browser_tests\": \"54af388a29a2d772d8ee00b687246863aca307f5\", \"capture_unittests\": \"8370ff94c476d7b8c038325650712b1b865556fd\", \"cast_unittests\": \"89f63f49ec94744d17e835b9adb18eea5a2662e2\", \"cc_unittests\": \"5a05858e563585639dd206cb532ee89bde8c3f29\", \"chrome_app_unittests\": \"ed932f2a96c121cfd001db492f6a4c552fb7df13\", \"chrome_cleaner_unittests\": \"0b9a577310301ac1ab026efc10f0d2e1e47fdd37\", \"chrome_elf_unittests\": \"383bef55b98e6ebdf20d484181188798631e5928\", \"chromedriver_unittests\": \"b7a3c4d1281f2e711d840500e34a9df001852e16\", \"components_browsertests\": \"bbeb842f2129e29b0a97c1ce0cb043e3c889b7ee\", \"components_unittests\": \"493d8b62edf6b04a224d4d5b42e6140e6a39bc3c\", \"compositor_unittests\": \"a807490773670786ff07315321d40fbf7ddc9b9f\", \"content_browsertests\": \"79ee406de56af289942a89a7325f80aef64bec16\", \"content_unittests\": \"104e68f5ae8c9977a0edc9e8b6c65b6c840e6711\", \"courgette_unittests\": \"c725ca5979a190556e7436ce0f798af592d6e11c\", \"crashpad_tests\": \"cf582ac6f64b54b0cc25c81310e42ad4785f7d9d\", \"cronet_tests\": \"2d9bc014cc5c763164040b2b2e28354bac1fe6c6\", \"cronet_unittests\": \"ed605f551885e73cbfd4a417198d191e17b807c5\", \"crypto_unittests\": \"2bcd04804f9fe00c0a83eccfeb257815c980eea9\", \"delayloads_unittests\": \"f822c934cce6a23c6681522cca903b327ac19256\", \"device_unittests\": \"b13a09bbd5a610479723eceb4b51b371e4cd0f3c\", \"display_unittests\": \"947ce1de50b7a73052aea86af5d7a63c26517498\", \"elevation_service_unittests\": \"b13f0e9249a954b8355a2a078a57c9e8875784d2\", \"events_unittests\": \"b9876803fff83ae1d1d42e2b5507b7e0310b8c88\", \"extensions_browsertests\": \"faceefaf3ff89ef90454474cc50e93a24436050e\", \"extensions_unittests\": \"f95c94b7924c2e2887259be685c218d6b2432fc5\", \"filesystem_service_unittests\": \"4ddef985e5d547e38317d33a49633dea948cc036\", \"gcm_unit_tests\": \"de3a41b889dc6265e20d2b92f2eb6121e8ad6551\", \"gcp_unittests\": \"109881f4d49146787909004e3052aeda687a59a6\", \"gfx_unittests\": \"b9b59b4570052676471d72714634c464c55dfdad\", \"gin_unittests\": \"b45773a3373b09b8a7f6bdff8680f13a890b359c\", \"google_apis_unittests\": \"cbb03da9e663dd298921254b0a48febc444b6e01\", \"gpu_unittests\": \"b2a7e8e6f33f9c19d77e92506da6e9b8bb16a831\", \"gwp_asan_unittests\": \"bdb7e454615f0f8e7a5f185f1c890fdd2b3f1f65\", \"headless_browsertests\": \"b43ac91e00eb87b02ae8a0f0d4d4624f7e1ab524\", \"headless_unittests\": \"348aed2e5eb065a246af9225ed10ecd454f30a7a\", \"install_static_unittests\": \"67455bcc07270bdf99f1fc95187b28befd3c39cc\", \"installer_util_unittests\": \"488e6fb6802afcf707305b7da7d9a5e017f3021f\", \"interactive_ui_tests\": \"c45793a5c1fb8c460aee7b62a707ccb94154f35d\", \"ipc_tests\": \"8a17c5292863f44608cf0b2bde495f883e646890\", \"jingle_unittests\": \"95060c7b483f497a0919a14ad5840f13fcba4d27\", \"latency_unittests\": \"a536b1fe61cf3d56cf9b5286cd8fcc760d3b6c44\", \"libjingle_xmpp_unittests\": \"5c8d9044710bb65d9dba9c456f1ed03567481b05\", \"liburlpattern_unittests\": \"85603534e43c823ba89be9c73c2176c4a25ce960\", \"media_blink_unittests\": \"1b8dc049756bece7769336d0c3660ffe2162fed4\", \"media_unittests\": \"6950157831b579fdb63a98a7ce096ddcecf67fd8\", \"message_center_unittests\": \"e0e31bbbe371b3a78d9bebc0e936b1a706f95f80\", \"midi_unittests\": \"7caa641f73ee1405bf557d28c3e6a702e07ad57b\", \"mojo_core_unittests\": \"e379fc39a7bf7c68630eb17deeaffc07e7e1525c\", \"mojo_unittests\": \"92c66a2af2b2e3baa35eb088d5679a800786c8ca\", \"nacl_loader_unittests\": \"bf941550128927a658cba332f98e19ebfb298d59\", \"native_theme_unittests\": \"54887ec934208a3c67eaa22151723142c1d14992\", \"net_unittests\": \"164743677f1fa2595f8613c3f9501eade17f3635\", \"notification_helper_unittests\": \"9dfb28a310ed3eee9e632dace94cf170f8b89008\", \"pdf_unittests\": \"c00dbd21d83146cf31b2989f8878a6d900654a68\", \"perfetto_unittests\": \"0ff1711e5d1c49e41a523bcbcdcd0a8ab466d551\", \"ppapi_unittests\": \"75031a2c682fadb6812fd49755b61dbd5199f822\", \"printing_unittests\": \"2d6b7f1e48ba2bcbd21111e5db45aaa61c0021fc\", \"remoting_unittests\": \"c610e5a51e83db427a2eead1d9bc701647a78e65\", \"sbox_integration_tests\": \"93bcd74cc639c50c0250b77ad99610fc81dcf606\", \"sbox_unittests\": \"a91a6af93289d004a5c0a50bcc8095be039d02ac\", \"sbox_validation_tests\": \"5a8e8c365c88e928032cc2857c1e55f0c4eb819d\", \"service_manager_unittests\": \"8730515b75e09fcdc8956cfc28db37fff2056020\", \"services_unittests\": \"915fc4b28be110f1a4182782bb111f22081aff24\", \"setup_unittests\": \"9e857b553cef8f834ca58e2b8965441f97f3537b\", \"shell_dialogs_unittests\": \"a2cc7419e339fa3b63d400840bfab4c622f26724\", \"skia_unittests\": \"01653f77585e6573d59f49d9b581449dce8f37f5\", \"snapshot_unittests\": \"80c9aab9e57d5d711830e3edd555e78170c69f48\", \"sql_unittests\": \"7f6b83e752787be6b5beed8f2fc80218231bc6e2\", \"storage_unittests\": \"6941223fc20b3985a61c27d3427946219ad2892e\", \"sync_integration_tests\": \"b951d89db359fc954cc4b317f938a0497247262a\", \"traffic_annotation_auditor_unittests\": \"ed6d4efd2da86df36937643be0fcaff3b895ed18\", \"ui_base_unittests\": \"5896edc35012ce6072f40f855a94592e0a377b4c\", \"ui_touch_selection_unittests\": \"494a4a3bcf37003926f9ef83faf1dd9a72189323\", \"unit_tests\": \"eb0a67b4b42291e96e559e87e3f61df13acab8cd\", \"updater_tests\": \"919e8cb4d678ba0faad62cb4b3d7a58ebb68c5fb\", \"url_unittests\": \"2da501f278a38ed18bd70d43d6dc16ff64c84779\", \"views_unittests\": \"e72fc94ef8fcff7d7c3994f2897a89427e5cd3a7\", \"viz_unittests\": \"103d86cd77e99ffc0216068e42d0dadad7408a4c\", \"vr_common_unittests\": \"f6afcc105c79fa77a2dda6ee730ec6f124521de8\", \"vr_pixeltests\": \"a79afb34f104622f46c865476c426ec3bfeb5676\", \"weblayer_browsertests\": \"3a85b41f1189271a5e7e9b79ff7cc5ab9f156047\", \"weblayer_unittests\": \"99b91fa41e69d87fa60522bef6806326b7e82593\", \"wm_unittests\": \"ff252ce41c9e40cb15fae6e8e9277833010a9753\", \"wtf_unittests\": \"9a70c0271999fbd31f31508b69aab1ae8bc9322d\", \"zlib_unittests\": \"27d2261a0214c40fe246bac81651fa22feaffd83\", \"zucchini_unittests\": \"6dee760ca2f9478f1ef0428c4cfd8e83df105bf1\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_27a86a15ec8feb386a5b02e9d1f544a49194bc134f7348519b0bd6cd38b20371_v2\", \"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-us-central1-b-32-0-gbsn\"], \"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\": [\"5671-1d9dea8\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611214743123123",
"status": "COMPLETED",
"status_changed_ts": "1611217956387579",
"tags": [
"build_address:luci.chromium.ci/win-asan/16926",
"builder:win-asan",
"buildset:commit/git/9d3c4a91f6c2cda7853beca707291e758da4ef44",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9d3c4a91f6c2cda7853beca707291e758da4ef44",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022942674316127680",
"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/8857512612257588896/+/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:513cb362899a9610",
"user_agent:luci-scheduler"
],
"updated_ts": "1611217956469427",
"url": "https://ci.chromium.org/b/8857512612257588896",
"utcnow_ts": "1611217958609438"
}
}
[08:32:38.625] Invocation finished in 53m43.319167335s with status SUCCEEDED