[08:26:32.847] New invocation is queued and will start shortly
[08:26:33.982] Starting the invocation (attempt 1)
[08:26:34.026] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:26:34.026] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:26:34.026] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9029553256542088352",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"86a5fedb72830ebdb5da7e3a70217fbb20019fc6\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9029553256542088352",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/86a5fedb72830ebdb5da7e3a70217fbb20019fc6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/86a5fedb72830ebdb5da7e3a70217fbb20019fc6",
"gitiles_ref:refs/heads/master"
]
}
[08:26:34.677] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1604910394111951",
"id": "8864123194542723472",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"86a5fedb72830ebdb5da7e3a70217fbb20019fc6\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1604910394569666",
"tags": [
"build_address:luci.chromium.ci/win-asan/15549",
"builder:win-asan",
"buildset:commit/git/86a5fedb72830ebdb5da7e3a70217fbb20019fc6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/86a5fedb72830ebdb5da7e3a70217fbb20019fc6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029553256542088352",
"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/8864123194542723472/+/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": "1604910394569789",
"url": "https://ci.chromium.org/b/8864123194542723472",
"utcnow_ts": "1604910394668900"
}
}
[08:26:34.677] Task URL: https://ci.chromium.org/b/8864123194542723472
[08:26:34.677] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:2:0) after 1m0s
[08:26:36.323] Received PubSub notification, asking Buildbucket for the build status
[08:26:36.465] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:34.724] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:2:0)
[08:27:34.724] Timer tick, asking Buildbucket for the build status
[08:27:34.887] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:34.887] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:4:0) after 1m0s
[08:28:34.900] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:4:0)
[08:28:34.900] Timer tick, asking Buildbucket for the build status
[08:28:35.018] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:35.018] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:5:0) after 1m0s
[08:29:35.176] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:5:0)
[08:29:35.176] Timer tick, asking Buildbucket for the build status
[08:29:35.319] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:35.319] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:6:0) after 1m0s
[08:30:35.607] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:6:0)
[08:30:35.607] Timer tick, asking Buildbucket for the build status
[08:30:35.718] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:35.718] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:7:0) after 1m0s
[08:31:35.888] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:7:0)
[08:31:35.888] Timer tick, asking Buildbucket for the build status
[08:31:36.113] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:36.113] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:8:0) after 1m0s
[08:32:36.163] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:8:0)
[08:32:36.163] Timer tick, asking Buildbucket for the build status
[08:32:37.489] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:37.490] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:9:0) after 1m0s
[08:33:37.509] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:9:0)
[08:33:37.509] Timer tick, asking Buildbucket for the build status
[08:33:37.564] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:37.564] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:10:0) after 1m0s
[08:34:37.546] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:10:0)
[08:34:37.546] Timer tick, asking Buildbucket for the build status
[08:34:37.651] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:37.651] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:11:0) after 1m0s
[08:35:37.678] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:11:0)
[08:35:37.678] Timer tick, asking Buildbucket for the build status
[08:35:38.106] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:38.106] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:12:0) after 1m0s
[08:36:38.314] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:12:0)
[08:36:38.314] Timer tick, asking Buildbucket for the build status
[08:36:38.545] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:38.545] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:13:0) after 1m0s
[08:37:38.625] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:13:0)
[08:37:38.625] Timer tick, asking Buildbucket for the build status
[08:37:38.868] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:38.868] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:14:0) after 1m0s
[08:38:38.994] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:14:0)
[08:38:38.994] Timer tick, asking Buildbucket for the build status
[08:38:39.143] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:39.143] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:15:0) after 1m0s
[08:39:39.265] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:15:0)
[08:39:39.265] Timer tick, asking Buildbucket for the build status
[08:39:41.395] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:41.395] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:16:0) after 1m0s
[08:40:41.416] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:16:0)
[08:40:41.416] Timer tick, asking Buildbucket for the build status
[08:40:41.556] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:41.556] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:17:0) after 1m0s
[08:41:41.628] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:17:0)
[08:41:41.628] Timer tick, asking Buildbucket for the build status
[08:41:41.836] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:41.836] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:18:0) after 1m0s
[08:42:41.853] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:18:0)
[08:42:41.853] Timer tick, asking Buildbucket for the build status
[08:42:42.308] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:42.308] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:19:0) after 1m0s
[08:43:42.334] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:19:0)
[08:43:42.334] Timer tick, asking Buildbucket for the build status
[08:43:42.452] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:42.452] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:20:0) after 1m0s
[08:44:42.482] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:20:0)
[08:44:42.482] Timer tick, asking Buildbucket for the build status
[08:44:42.583] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:42.583] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:21:0) after 1m0s
[08:45:42.614] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:21:0)
[08:45:42.614] Timer tick, asking Buildbucket for the build status
[08:45:42.757] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:42.757] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:22:0) after 1m0s
[08:46:42.786] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:22:0)
[08:46:42.786] Timer tick, asking Buildbucket for the build status
[08:46:42.890] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:42.890] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:23:0) after 1m0s
[08:47:42.913] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:23:0)
[08:47:42.913] Timer tick, asking Buildbucket for the build status
[08:47:43.136] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:43.136] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:24:0) after 1m0s
[08:48:43.168] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:24:0)
[08:48:43.168] Timer tick, asking Buildbucket for the build status
[08:48:43.290] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:43.290] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:25:0) after 1m0s
[08:49:43.327] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:25:0)
[08:49:43.327] Timer tick, asking Buildbucket for the build status
[08:49:43.410] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:43.410] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:26:0) after 1m0s
[08:50:43.450] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:26:0)
[08:50:43.450] Timer tick, asking Buildbucket for the build status
[08:50:43.522] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:43.522] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:27:0) after 1m0s
[08:51:43.604] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:27:0)
[08:51:43.604] Timer tick, asking Buildbucket for the build status
[08:51:43.714] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:43.714] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:28:0) after 1m0s
[08:52:43.763] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:28:0)
[08:52:43.763] Timer tick, asking Buildbucket for the build status
[08:52:44.262] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:44.262] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:29:0) after 1m0s
[08:53:44.442] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:29:0)
[08:53:44.442] Timer tick, asking Buildbucket for the build status
[08:53:44.557] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:44.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:30:0) after 1m0s
[08:54:44.658] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:30:0)
[08:54:44.658] Timer tick, asking Buildbucket for the build status
[08:54:44.723] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:44.723] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:31:0) after 1m0s
[08:55:44.868] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:31:0)
[08:55:44.868] Timer tick, asking Buildbucket for the build status
[08:55:45.735] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:45.735] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:32:0) after 1m0s
[08:56:45.829] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:32:0)
[08:56:45.829] Timer tick, asking Buildbucket for the build status
[08:56:46.079] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:46.079] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:33:0) after 1m0s
[08:57:46.105] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:33:0)
[08:57:46.105] Timer tick, asking Buildbucket for the build status
[08:57:46.701] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:46.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:34:0) after 1m0s
[08:58:46.756] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:34:0)
[08:58:46.756] Timer tick, asking Buildbucket for the build status
[08:58:47.046] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:47.046] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:35:0) after 1m0s
[08:59:47.130] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:35:0)
[08:59:47.130] Timer tick, asking Buildbucket for the build status
[08:59:47.208] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:47.208] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:36:0) after 1m0s
[09:00:47.261] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:36:0)
[09:00:47.261] Timer tick, asking Buildbucket for the build status
[09:00:47.364] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:47.364] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:37:0) after 1m0s
[09:01:47.443] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:37:0)
[09:01:47.443] Timer tick, asking Buildbucket for the build status
[09:01:48.084] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:48.084] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:38:0) after 1m0s
[09:02:48.191] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:38:0)
[09:02:48.191] Timer tick, asking Buildbucket for the build status
[09:02:48.297] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:48.297] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:39:0) after 1m0s
[09:03:48.385] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:39:0)
[09:03:48.386] Timer tick, asking Buildbucket for the build status
[09:03:48.509] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:48.510] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:40:0) after 1m0s
[09:04:48.553] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:40:0)
[09:04:48.553] Timer tick, asking Buildbucket for the build status
[09:04:48.628] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:48.628] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:41:0) after 1m0s
[09:05:48.691] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:41:0)
[09:05:48.692] Timer tick, asking Buildbucket for the build status
[09:05:49.268] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:49.268] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:42:0) after 1m0s
[09:06:49.529] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:42:0)
[09:06:49.529] Timer tick, asking Buildbucket for the build status
[09:06:49.698] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:49.698] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:43:0) after 1m0s
[09:07:49.869] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:43:0)
[09:07:49.869] Timer tick, asking Buildbucket for the build status
[09:07:50.203] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:50.205] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:44:0) after 1m0s
[09:08:50.351] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:44:0)
[09:08:50.351] Timer tick, asking Buildbucket for the build status
[09:08:50.462] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:50.463] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:45:0) after 1m0s
[09:09:50.460] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:45:0)
[09:09:50.460] Timer tick, asking Buildbucket for the build status
[09:09:50.595] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:50.596] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:46:0) after 1m0s
[09:10:50.596] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:46:0)
[09:10:50.596] Timer tick, asking Buildbucket for the build status
[09:10:51.285] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:51.285] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:47:0) after 1m0s
[09:11:51.353] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:47:0)
[09:11:51.353] Timer tick, asking Buildbucket for the build status
[09:11:51.524] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:51.524] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:48:0) after 1m0s
[09:12:51.526] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:48:0)
[09:12:51.526] Timer tick, asking Buildbucket for the build status
[09:12:51.611] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:51.611] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:49:0) after 1m0s
[09:13:51.700] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:49:0)
[09:13:51.700] Timer tick, asking Buildbucket for the build status
[09:13:51.834] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:51.834] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:50:0) after 1m0s
[09:14:51.889] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:50:0)
[09:14:51.889] Timer tick, asking Buildbucket for the build status
[09:14:52.021] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:52.021] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:51:0) after 1m0s
[09:15:52.081] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:51:0)
[09:15:52.081] Timer tick, asking Buildbucket for the build status
[09:15:52.214] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:52.214] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:52:0) after 1m0s
[09:16:52.289] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:52:0)
[09:16:52.289] Timer tick, asking Buildbucket for the build status
[09:16:52.505] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:52.505] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:53:0) after 1m0s
[09:17:52.507] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:53:0)
[09:17:52.507] Timer tick, asking Buildbucket for the build status
[09:17:52.705] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:52.705] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:54:0) after 1m0s
[09:18:52.798] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:54:0)
[09:18:52.798] Timer tick, asking Buildbucket for the build status
[09:18:52.936] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:52.936] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:55:0) after 1m0s
[09:19:53.100] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:55:0)
[09:19:53.100] Timer tick, asking Buildbucket for the build status
[09:19:53.256] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:53.257] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:56:0) after 1m0s
[09:20:53.401] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:56:0)
[09:20:53.401] Timer tick, asking Buildbucket for the build status
[09:20:53.482] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:53.482] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:57:0) after 1m0s
[09:21:53.597] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:57:0)
[09:21:53.597] Timer tick, asking Buildbucket for the build status
[09:21:54.025] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:54.025] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:58:0) after 1m0s
[09:22:54.103] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:58:0)
[09:22:54.103] Timer tick, asking Buildbucket for the build status
[09:22:54.236] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:54.236] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:59:0) after 1m0s
[09:23:54.469] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:59:0)
[09:23:54.469] Timer tick, asking Buildbucket for the build status
[09:23:54.569] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:54.569] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:60:0) after 1m0s
[09:24:54.727] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:60:0)
[09:24:54.727] Timer tick, asking Buildbucket for the build status
[09:24:54.828] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:54.828] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:61:0) after 1m0s
[09:25:54.924] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:61:0)
[09:25:54.924] Timer tick, asking Buildbucket for the build status
[09:25:55.041] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:55.041] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:62:0) after 1m0s
[09:26:55.224] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:62:0)
[09:26:55.224] Timer tick, asking Buildbucket for the build status
[09:26:55.381] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:55.381] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:63:0) after 1m0s
[09:27:55.666] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:63:0)
[09:27:55.666] Timer tick, asking Buildbucket for the build status
[09:27:55.742] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:55.742] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:64:0) after 1m0s
[09:28:56.022] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:64:0)
[09:28:56.022] Timer tick, asking Buildbucket for the build status
[09:28:56.116] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:56.116] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:65:0) after 1m0s
[09:29:56.290] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:65:0)
[09:29:56.290] Timer tick, asking Buildbucket for the build status
[09:29:56.692] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:56.692] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:66:0) after 1m0s
[09:30:56.712] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:66:0)
[09:30:56.712] Timer tick, asking Buildbucket for the build status
[09:30:56.876] Build 8864123194542723472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:56.876] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029553256542088352:67:0) after 1m0s
[09:31:06.569] Received PubSub notification, asking Buildbucket for the build status
[09:31:06.979] Build 8864123194542723472: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[09:31:06.979] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1604914264510221",
"created_by": "project:chromium",
"created_ts": "1604910394111951",
"id": "8864123194542723472",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"86a5fedb72830ebdb5da7e3a70217fbb20019fc6\"}}",
"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\": \"86a5fedb72830ebdb5da7e3a70217fbb20019fc6\", \"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-8-b9eu\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15549, \"clang_revision\": \"llvmorg-12-init-10666-gc9f69ee7-1\", \"got_angle_revision\": \"d4439824c55428a699aa543a2f397d06a00841b6\", \"got_dawn_revision\": \"3e17e33724b4d0b4c4fc1985340882e1f543e3a0\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"86a5fedb72830ebdb5da7e3a70217fbb20019fc6\", \"got_revision_cp\": \"refs/heads/master@{#825270}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"4ed9d3498dcffa987acba1a8007ff8dec336f263\", \"got_v8_revision\": \"4075f123295bb39d50e472b7af1fc04d4f4fe228\", \"got_v8_revision_cp\": \"refs/heads/8.8.250@{#1}\", \"got_webrtc_revision\": \"9c99b7964fabb16a022293ffbd2804f9b46551f6\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32565}\", \"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\": \"86a5fedb72830ebdb5da7e3a70217fbb20019fc6\", \"swarm_hashes_refs/heads/master(at){#825270}_without_patch\": {\"absl_hardening_tests\": \"085a8ebbf62501d8413c0cb0992987121311a2e4\", \"accessibility_unittests\": \"ab37d5f359231133a32af8e397db35b83cf973a8\", \"angle_unittests\": \"ecf59aec24fd4d23de882960fd06e13d62322829\", \"app_shell_unittests\": \"b20907105524e9fa0cd1af083023e3a1d91179c5\", \"aura_unittests\": \"00003df23c3a731adb95914ca6effe87c653d0ec\", \"base_unittests\": \"51f92f640f7367d1ee8bc1511f65ae57be912fab\", \"base_util_unittests\": \"a1a00cf17da2ac3ca79f4d87cec20a4092390266\", \"blink_common_unittests\": \"ceddd392c189084b71b805c82a50949874670e99\", \"blink_fuzzer_unittests\": \"de224f06e98dba7c509d46bd64fa9f5ce3e879a3\", \"blink_heap_unittests\": \"e4015917ecda92cd43b4d208a84e443bfc828fc7\", \"blink_platform_unittests\": \"ae48f4a022b74210c28a460a88398bbe7800331d\", \"blink_unittests\": \"5bec0de1dca8f38a54631384aa4f528f14952735\", \"boringssl_crypto_tests\": \"92363c7e62f5fa9a6ee8cd7fd9af79d3f8705ef1\", \"boringssl_ssl_tests\": \"014c62ea717eef7a4d50f6e6338407defc30fe3b\", \"browser_switcher_bho_unittests\": \"b1cf73e5754d677acd762f4d8491f9ceaaaa45b1\", \"browser_tests\": \"c70f4136de6035402a8b2256c10468946280036c\", \"capture_unittests\": \"42529c9b892a7353b4809e7ec459ed1972220954\", \"cast_unittests\": \"5acfa2b2384e39296d864b6ec53b4df88cb24f82\", \"cc_unittests\": \"5e2d28bd9f1238303da7687f4998ed4ceb692e1b\", \"chrome_app_unittests\": \"91aaa34721c94a751a0b4a1874fb99eff6e418c0\", \"chrome_cleaner_unittests\": \"6f67dce96076d46ccd0a0f199b3162af5596d50e\", \"chrome_elf_unittests\": \"486467dd52c9151775e8922d5b8f4d341c3d1c2a\", \"chromedriver_unittests\": \"b87dec79c73301a5fb505699abf3d959b35579a5\", \"components_browsertests\": \"8881901785d6c81f22bca767fa8fa996af1dd28d\", \"components_unittests\": \"7cb85c8882471f9047fd6f28e536eb9c835fea31\", \"compositor_unittests\": \"b42cf9bb67ae5968244efc0a743e4b94c17cf615\", \"content_browsertests\": \"4880373cca34844015459c6eaaf586b462c5b749\", \"content_unittests\": \"3f62e03e086c870e831a6fe9878c7c96589f6eb9\", \"courgette_unittests\": \"e07c76916fa092b8432bfd50c39e503f9531a682\", \"crashpad_tests\": \"e820f6e63d55a930b873845d480b367bfc28d668\", \"cronet_tests\": \"53b05d0739c03fac6cd21a0c9784802605721adf\", \"cronet_unittests\": \"21b61985992b98eeafc777a35ebe958e921b67b3\", \"crypto_unittests\": \"2e85593b6b1b88304bc43821bccc861055c140bd\", \"delayloads_unittests\": \"f88d9831e4084342c65ceeca3225969f86bed924\", \"device_unittests\": \"00005a295f06dff188d3b866c22c566404928dc3\", \"display_unittests\": \"78ddd7ef8ac507c2a707f6680a7485b9a268e614\", \"elevation_service_unittests\": \"9576232a239627e2ab406556d9fe306ca702aefd\", \"events_unittests\": \"8d8cbadb9119e99fd8a4f4592716f3e3fe0c6a50\", \"extensions_browsertests\": \"e32041cf309b72b76318eac71dc8cf1ab0b2a2ed\", \"extensions_unittests\": \"713b72dd63b25d2c55d85ccf8ee1d8a078a5a7a3\", \"filesystem_service_unittests\": \"787a75caef8a7fc795d497a1973b4d48668a1558\", \"gcm_unit_tests\": \"3109a8e5c3ac60cf1fa0f2b711b84705a81ecda1\", \"gcp_unittests\": \"5eed6636efbbe4ffc045341686445aca4765a6b8\", \"gfx_unittests\": \"a134c442820c027c46f5621d515cb4c3c847e16a\", \"gin_unittests\": \"3efe649762f757d52aa0a8b8f3ebf1f902090a15\", \"google_apis_unittests\": \"3f111cd6ccf4bda8e879a757f3a5260733235a78\", \"gpu_unittests\": \"5eeb277d02d7c7bed41e820acbb315918cf474d8\", \"gwp_asan_unittests\": \"35b01d6aabdd1872016d66b0816056ed7d14d5d1\", \"headless_browsertests\": \"044e413276d63ffe4addeb1951cf6a0bf8902d81\", \"headless_unittests\": \"b885355925112d1be6ab5d84eb5e8ca0c105f17a\", \"install_static_unittests\": \"e8788f6d84228fb9fcee19e691cf5baef674a8fc\", \"installer_util_unittests\": \"4db0e83715afacad4bffe3859adc4e4daa306afc\", \"interactive_ui_tests\": \"4220f5dd952f9789044cac7dd9d53706d7b30a70\", \"ipc_tests\": \"4f840445597f6be12899b8d7b7fb90c20291bf76\", \"jingle_unittests\": \"dbb45a367b4d92c38d89a3ec03df183abefb2e4a\", \"latency_unittests\": \"1da3735f7e6592e41443ff25db2fa377200851dc\", \"libjingle_xmpp_unittests\": \"d981bff5700b51fa83c7569ee517e565cce985ec\", \"liburlpattern_unittests\": \"d38fb55c45fc8d5505a3cd644278e43c933890a3\", \"media_blink_unittests\": \"18dabaa9fb989a6ad62a8b4536b89ca646337781\", \"media_unittests\": \"5354406ba4b0250795c25865c1cb30acdd4fdea3\", \"message_center_unittests\": \"834f3e68666d4cf64c9a181b98a9c53f792ff1f3\", \"midi_unittests\": \"e7de74274e7fea919f545b2ae32690232ff26648\", \"mojo_core_unittests\": \"102c2b884e06df2c82315ca438925932a9246cdc\", \"mojo_unittests\": \"54a81cccf21c21b6d650e83aa530687d4c9717fd\", \"nacl_loader_unittests\": \"b2f67c7c9e247e1847658b82e600c1f336893e46\", \"native_theme_unittests\": \"407df8510e153f5c980149f0444bc3102566fcc8\", \"net_unittests\": \"d5a3e7e5c4deba17c3fca964a89cd9ebdb948fe8\", \"notification_helper_unittests\": \"0e4652338d5e88f5813c83e4847445cd2ed5a271\", \"pdf_unittests\": \"8838abc9c970ca617aba46aae29fd6c05f3538ca\", \"perfetto_unittests\": \"54f53edc93116c3065e182d3a08196a3668e3135\", \"ppapi_unittests\": \"7fd2e74241745bf0f027482931c27abab0ec10a4\", \"printing_unittests\": \"bdb6ecd147f6209a58d79cd758179f6f994321e6\", \"remoting_unittests\": \"a42c673edd384c216b2c02612a55aaf316bdb015\", \"sbox_integration_tests\": \"103022aefed52fb7566b4c5bc46ef667b74fbaef\", \"sbox_unittests\": \"d48ce8b399370b4ddf5327b240100cb7aa11ebc9\", \"sbox_validation_tests\": \"dcede3e8712148decc0c2c1f2a1e5bf3a388b60d\", \"service_manager_unittests\": \"ef8594fc5831bcbf3692fe469108f9ae7a23fe84\", \"services_unittests\": \"c691c39c312387c1b7b0760ed5200df6898f361c\", \"setup_unittests\": \"00c93e12dafdd4537ecf0198ef4b4a305fbe1745\", \"shell_dialogs_unittests\": \"131f32c429fa4f4d2dd9b921ea0621316d0bdc66\", \"skia_unittests\": \"3a8fe76c07409f05bc0c52376aa49282d29cfb7b\", \"snapshot_unittests\": \"c22da2fd7de027b2294995e77972e22617dcd55e\", \"sql_unittests\": \"76904b238517b3388c9c3521b4a6df25edd4b01a\", \"storage_unittests\": \"9832241e24d9a3ec3a41be00cf3607c6fb91bae2\", \"sync_integration_tests\": \"9bb80301345ab187332fbfc8bfb59c13dcd22c86\", \"traffic_annotation_auditor_unittests\": \"44b653ea50ea40b7e850c04c1ecd32538b67e746\", \"ui_base_unittests\": \"6ccd0adea0d5afab261d0dabef810e92bf3b743b\", \"ui_touch_selection_unittests\": \"4757aad4858ccf0ce5e58ff01e4819c3ddc34226\", \"unit_tests\": \"3fbd7297c65d1cd2cb3ff811d0baf9b923d6cf71\", \"updater_tests\": \"de7c930f6153d39442aa07ec75a3d6a7ca047dd3\", \"url_unittests\": \"4ca760450e5c638a49718e3521002021c47ef00b\", \"views_unittests\": \"e499365eca2541231c8c3c410634ce40eb12022e\", \"viz_unittests\": \"70802d56ca931145a0776493d929e5f40039be0b\", \"vr_common_unittests\": \"31a4578dccc0acdc0386239723668ace56af6e68\", \"vr_pixeltests\": \"f24565c61f19a9cf256915acf3aa53a5993fb297\", \"weblayer_browsertests\": \"a1dc52308d055ec825def0aa7e06840bd9900f79\", \"weblayer_unittests\": \"6bd1d0512d2d971c52f2dabdbf5a5dd2646fad19\", \"wm_unittests\": \"aa2012528e5bd0046468bbe6538da833793f870a\", \"wtf_unittests\": \"b87836adddd47352f9b92adade83fad57a071094\", \"zlib_unittests\": \"0562529299328adb6d280dcc18b2b105449b1989\", \"zucchini_unittests\": \"db8ab11b9963ca93ff6c4229a3ed91fc445f955c\"}}, \"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-8-b9eu\"], \"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\": [\"5626-39642e9\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1604910395473740",
"status": "COMPLETED",
"status_changed_ts": "1604914265079813",
"tags": [
"build_address:luci.chromium.ci/win-asan/15549",
"builder:win-asan",
"buildset:commit/git/86a5fedb72830ebdb5da7e3a70217fbb20019fc6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/86a5fedb72830ebdb5da7e3a70217fbb20019fc6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029553256542088352",
"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/8864123194542723472/+/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:4fc4eeb5c93e2e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1604914265173778",
"url": "https://ci.chromium.org/b/8864123194542723472",
"utcnow_ts": "1604914266929779"
}
}
[09:31:06.979] Invocation finished in 1h4m34.158236004s with status SUCCEEDED