[19:16:33.092] New invocation is queued and will start shortly
[19:16:34.703] Starting the invocation (attempt 1)
[19:16:34.750] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:16:34.751] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:16:34.751] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9025707289226795856",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"e973d3e578794483da1cf89663ea4f4b15051d7f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9025707289226795856",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/e973d3e578794483da1cf89663ea4f4b15051d7f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e973d3e578794483da1cf89663ea4f4b15051d7f",
"gitiles_ref:refs/heads/master"
]
}
[19:16:36.804] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1608578194941560",
"id": "8860277226619954656",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e973d3e578794483da1cf89663ea4f4b15051d7f\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1608578196332887",
"tags": [
"build_address:luci.chromium.ci/win-asan/16368",
"builder:win-asan",
"buildset:commit/git/e973d3e578794483da1cf89663ea4f4b15051d7f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e973d3e578794483da1cf89663ea4f4b15051d7f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025707289226795856",
"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/8860277226619954656/+/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": "1608578196333119",
"url": "https://ci.chromium.org/b/8860277226619954656",
"utcnow_ts": "1608578196787823"
}
}
[19:16:36.804] Task URL: https://ci.chromium.org/b/8860277226619954656
[19:16:36.804] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:2:0) after 1m0s
[19:16:39.606] Received PubSub notification, asking Buildbucket for the build status
[19:16:39.715] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:36.868] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:2:0)
[19:17:36.868] Timer tick, asking Buildbucket for the build status
[19:17:36.998] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:36.998] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:4:0) after 1m0s
[19:18:37.014] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:4:0)
[19:18:37.014] Timer tick, asking Buildbucket for the build status
[19:18:37.218] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:37.218] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:5:0) after 1m0s
[19:19:37.266] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:5:0)
[19:19:37.266] Timer tick, asking Buildbucket for the build status
[19:19:37.475] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:37.475] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:6:0) after 1m0s
[19:20:37.514] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:6:0)
[19:20:37.514] Timer tick, asking Buildbucket for the build status
[19:20:37.978] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:37.978] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:7:0) after 1m0s
[19:21:38.006] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:7:0)
[19:21:38.006] Timer tick, asking Buildbucket for the build status
[19:21:38.697] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:38.697] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:8:0) after 1m0s
[19:22:38.672] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:8:0)
[19:22:38.672] Timer tick, asking Buildbucket for the build status
[19:22:38.750] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:38.750] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:9:0) after 1m0s
[19:23:38.772] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:9:0)
[19:23:38.772] Timer tick, asking Buildbucket for the build status
[19:23:38.830] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:38.830] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:10:0) after 1m0s
[19:24:38.853] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:10:0)
[19:24:38.853] Timer tick, asking Buildbucket for the build status
[19:24:38.925] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:38.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:11:0) after 1m0s
[19:25:38.950] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:11:0)
[19:25:38.950] Timer tick, asking Buildbucket for the build status
[19:25:39.020] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:39.020] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:12:0) after 1m0s
[19:26:39.034] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:12:0)
[19:26:39.034] Timer tick, asking Buildbucket for the build status
[19:26:39.165] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:39.165] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:13:0) after 1m0s
[19:27:39.255] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:13:0)
[19:27:39.255] Timer tick, asking Buildbucket for the build status
[19:27:39.478] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:39.478] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:14:0) after 1m0s
[19:28:39.664] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:14:0)
[19:28:39.664] Timer tick, asking Buildbucket for the build status
[19:28:39.864] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:39.864] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:15:0) after 1m0s
[19:29:39.970] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:15:0)
[19:29:39.970] Timer tick, asking Buildbucket for the build status
[19:29:40.085] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:40.085] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:16:0) after 1m0s
[19:30:40.163] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:16:0)
[19:30:40.163] Timer tick, asking Buildbucket for the build status
[19:30:42.205] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:42.205] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:17:0) after 1m0s
[19:31:42.251] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:17:0)
[19:31:42.251] Timer tick, asking Buildbucket for the build status
[19:31:42.576] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:42.576] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:18:0) after 1m0s
[19:32:42.708] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:18:0)
[19:32:42.708] Timer tick, asking Buildbucket for the build status
[19:32:43.038] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:43.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:19:0) after 1m0s
[19:33:43.130] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:19:0)
[19:33:43.130] Timer tick, asking Buildbucket for the build status
[19:33:43.287] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:43.287] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:20:0) after 1m0s
[19:34:43.360] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:20:0)
[19:34:43.360] Timer tick, asking Buildbucket for the build status
[19:34:43.638] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:43.638] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:21:0) after 1m0s
[19:35:43.653] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:21:0)
[19:35:43.653] Timer tick, asking Buildbucket for the build status
[19:35:43.899] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:43.899] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:22:0) after 1m0s
[19:36:43.944] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:22:0)
[19:36:43.944] Timer tick, asking Buildbucket for the build status
[19:36:44.110] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:44.110] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:23:0) after 1m0s
[19:37:44.136] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:23:0)
[19:37:44.136] Timer tick, asking Buildbucket for the build status
[19:37:44.289] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:44.289] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:24:0) after 1m0s
[19:38:44.310] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:24:0)
[19:38:44.310] Timer tick, asking Buildbucket for the build status
[19:38:44.533] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:44.533] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:25:0) after 1m0s
[19:39:44.598] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:25:0)
[19:39:44.598] Timer tick, asking Buildbucket for the build status
[19:39:45.148] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:45.148] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:26:0) after 1m0s
[19:40:45.182] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:26:0)
[19:40:45.182] Timer tick, asking Buildbucket for the build status
[19:40:45.451] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:45.451] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:27:0) after 1m0s
[19:41:45.520] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:27:0)
[19:41:45.520] Timer tick, asking Buildbucket for the build status
[19:41:45.931] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:45.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:28:0) after 1m0s
[19:42:46.016] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:28:0)
[19:42:46.016] Timer tick, asking Buildbucket for the build status
[19:42:46.365] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:46.365] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:29:0) after 1m0s
[19:43:46.400] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:29:0)
[19:43:46.400] Timer tick, asking Buildbucket for the build status
[19:43:46.683] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:46.683] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:30:0) after 1m0s
[19:44:46.778] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:30:0)
[19:44:46.778] Timer tick, asking Buildbucket for the build status
[19:44:46.986] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:46.986] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:31:0) after 1m0s
[19:45:47.101] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:31:0)
[19:45:47.101] Timer tick, asking Buildbucket for the build status
[19:45:47.269] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:47.269] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:32:0) after 1m0s
[19:46:47.326] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:32:0)
[19:46:47.326] Timer tick, asking Buildbucket for the build status
[19:46:48.123] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:48.123] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:33:0) after 1m0s
[19:47:48.216] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:33:0)
[19:47:48.216] Timer tick, asking Buildbucket for the build status
[19:47:48.335] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:48.335] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:34:0) after 1m0s
[19:48:48.417] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:34:0)
[19:48:48.417] Timer tick, asking Buildbucket for the build status
[19:48:48.640] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:48.640] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:35:0) after 1m0s
[19:49:48.665] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:35:0)
[19:49:48.665] Timer tick, asking Buildbucket for the build status
[19:49:48.926] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:48.926] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:36:0) after 1m0s
[19:50:48.950] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:36:0)
[19:50:48.950] Timer tick, asking Buildbucket for the build status
[19:50:49.117] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:49.117] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:37:0) after 1m0s
[19:51:49.135] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:37:0)
[19:51:49.135] Timer tick, asking Buildbucket for the build status
[19:51:50.261] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:50.261] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:38:0) after 1m0s
[19:52:50.333] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:38:0)
[19:52:50.333] Timer tick, asking Buildbucket for the build status
[19:52:50.754] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:50.754] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:39:0) after 1m0s
[19:53:50.758] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:39:0)
[19:53:50.758] Timer tick, asking Buildbucket for the build status
[19:53:50.931] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:50.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:40:0) after 1m0s
[19:54:50.956] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:40:0)
[19:54:50.956] Timer tick, asking Buildbucket for the build status
[19:54:51.099] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:51.099] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:41:0) after 1m0s
[19:55:51.120] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:41:0)
[19:55:51.120] Timer tick, asking Buildbucket for the build status
[19:55:51.197] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:51.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:42:0) after 1m0s
[19:56:51.244] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:42:0)
[19:56:51.244] Timer tick, asking Buildbucket for the build status
[19:56:51.682] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:51.682] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:43:0) after 1m0s
[19:57:51.704] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:43:0)
[19:57:51.704] Timer tick, asking Buildbucket for the build status
[19:57:52.028] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:52.028] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:44:0) after 1m0s
[19:58:52.098] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:44:0)
[19:58:52.098] Timer tick, asking Buildbucket for the build status
[19:58:52.624] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:52.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:45:0) after 1m0s
[19:59:53.947] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:45:0)
[19:59:53.947] Timer tick, asking Buildbucket for the build status
[19:59:54.085] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:54.085] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:46:0) after 1m0s
[20:00:54.123] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:46:0)
[20:00:54.123] Timer tick, asking Buildbucket for the build status
[20:00:54.321] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:54.321] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:47:0) after 1m0s
[20:01:54.391] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:47:0)
[20:01:54.391] Timer tick, asking Buildbucket for the build status
[20:01:55.337] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:55.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:48:0) after 1m0s
[20:02:55.381] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:48:0)
[20:02:55.381] Timer tick, asking Buildbucket for the build status
[20:02:55.618] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:55.618] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:49:0) after 1m0s
[20:03:55.678] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:49:0)
[20:03:55.678] Timer tick, asking Buildbucket for the build status
[20:03:56.124] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:56.124] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:50:0) after 1m0s
[20:04:56.166] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:50:0)
[20:04:56.167] Timer tick, asking Buildbucket for the build status
[20:04:56.260] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:56.260] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:51:0) after 1m0s
[20:05:56.363] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:51:0)
[20:05:56.363] Timer tick, asking Buildbucket for the build status
[20:05:56.476] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:56.476] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:52:0) after 1m0s
[20:06:56.553] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:52:0)
[20:06:56.553] Timer tick, asking Buildbucket for the build status
[20:06:56.749] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:56.749] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:53:0) after 1m0s
[20:07:56.771] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:53:0)
[20:07:56.771] Timer tick, asking Buildbucket for the build status
[20:07:56.870] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:56.871] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:54:0) after 1m0s
[20:08:57.006] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:54:0)
[20:08:57.006] Timer tick, asking Buildbucket for the build status
[20:08:57.329] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:57.329] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:55:0) after 1m0s
[20:09:57.353] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:55:0)
[20:09:57.353] Timer tick, asking Buildbucket for the build status
[20:09:57.556] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:57.556] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:56:0) after 1m0s
[20:10:57.629] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:56:0)
[20:10:57.629] Timer tick, asking Buildbucket for the build status
[20:10:57.717] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:57.717] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:57:0) after 1m0s
[20:11:57.895] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:57:0)
[20:11:57.895] Timer tick, asking Buildbucket for the build status
[20:11:58.413] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:58.413] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:58:0) after 1m0s
[20:12:58.468] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:58:0)
[20:12:58.468] Timer tick, asking Buildbucket for the build status
[20:12:59.050] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:59.050] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:59:0) after 1m0s
[20:13:59.073] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:59:0)
[20:13:59.073] Timer tick, asking Buildbucket for the build status
[20:13:59.207] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:59.207] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:60:0) after 1m0s
[20:14:59.276] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:60:0)
[20:14:59.276] Timer tick, asking Buildbucket for the build status
[20:14:59.375] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:59.375] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:61:0) after 1m0s
[20:15:59.511] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:61:0)
[20:15:59.511] Timer tick, asking Buildbucket for the build status
[20:15:59.585] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:59.585] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:62:0) after 1m0s
[20:16:59.682] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:62:0)
[20:16:59.682] Timer tick, asking Buildbucket for the build status
[20:16:59.808] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:59.808] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:63:0) after 1m0s
[20:17:59.851] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:63:0)
[20:17:59.851] Timer tick, asking Buildbucket for the build status
[20:18:00.267] Build 8860277226619954656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:00.267] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025707289226795856:64:0) after 1m0s
[20:18:06.674] Received PubSub notification, asking Buildbucket for the build status
[20:18:06.764] Build 8860277226619954656: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[20:18:06.764] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1608581884659137",
"created_by": "project:chromium",
"created_ts": "1608578194941560",
"id": "8860277226619954656",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e973d3e578794483da1cf89663ea4f4b15051d7f\"}}",
"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\": \"e973d3e578794483da1cf89663ea4f4b15051d7f\", \"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-6-gpyc\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16368, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"4ebcee3eb02ffdd586e20965b9c4bb609876db36\", \"got_dawn_revision\": \"5d4fd88a7403aa255d49919d011af54c691b344a\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"e973d3e578794483da1cf89663ea4f4b15051d7f\", \"got_revision_cp\": \"refs/heads/master@{#838684}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"1cc5b3357d2ff3c81ef7ec0b07a6660796bde5cd\", \"got_v8_revision\": \"a89143c85285d6eb74ffcbae54818048846de360\", \"got_v8_revision_cp\": \"refs/heads/8.9.180@{#1}\", \"got_webrtc_revision\": \"1e98f95391647887d0db25dd5d9dcfba7f87ebd7\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32866}\", \"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\": \"e973d3e578794483da1cf89663ea4f4b15051d7f\", \"swarm_hashes_refs/heads/master(at){#838684}_without_patch\": {\"absl_hardening_tests\": \"82850a7f813fd9a083ed7982c7443a5c5aaee4ca\", \"accessibility_unittests\": \"086c3c93d31d67819ba3a2c49955d52a00f78b9a\", \"angle_unittests\": \"1d65f451ec25b0ea51f44412a7b7b1e790c5ed2e\", \"app_shell_unittests\": \"1de6527c712e6f905634b13f629340f8b2eb7c75\", \"aura_unittests\": \"d436d816edff83bc916c92d0f51125a0a30a18c7\", \"base_unittests\": \"f5c0826e0e4d92fcfc2922d8bff53691a7925a1d\", \"base_util_unittests\": \"97ef970a49bb03884aa200b47428c94f744a4334\", \"blink_common_unittests\": \"0843d49911b50e5d00071647cb96b764d6bd435d\", \"blink_fuzzer_unittests\": \"75d4931676170f42cfa7413c8ab37afbec77cb73\", \"blink_heap_unittests\": \"fee91391c9bf24aa9a03be8d1cd445354f748c58\", \"blink_platform_unittests\": \"02b2191e3eddb6aad8c7ec1af260ba24ebec855f\", \"blink_unittests\": \"5f49c45f168c9852606d17f362561314125db085\", \"boringssl_crypto_tests\": \"b5f43e4915c555f0f0a79a2e7a89dff7d4ae57c8\", \"boringssl_ssl_tests\": \"4b332d518ca3d3ce5532690fbc210ee41572403f\", \"browser_switcher_bho_unittests\": \"31cd18d8b1eec89e415b331a9d408f1e0dc312e8\", \"browser_tests\": \"2193809e96a9e6594d29560f5307986c224858fb\", \"capture_unittests\": \"a86685d8f8b03f158d17e51d6e2979fd73732972\", \"cast_unittests\": \"1907b1f41e6c455d580275bf159676690f7f8e49\", \"cc_unittests\": \"3b83ebab9bf226266ce09bc35850553813b40960\", \"chrome_app_unittests\": \"12c81b1bb3136aef79df788e8d4e80a465489231\", \"chrome_cleaner_unittests\": \"7f2e271f440248248808e7194aca6b88bcfa6e64\", \"chrome_elf_unittests\": \"34777052068e33c9fa150f0ee559c0274eb1e330\", \"chromedriver_unittests\": \"1ebfec9503c009905074c31bc3fc1c6e76e27edd\", \"components_browsertests\": \"693b769e8de3dbb165996a4959142bc77a025c7e\", \"components_unittests\": \"ec7ebca3b52677cdfd3beae72d14d04c262e4db9\", \"compositor_unittests\": \"dd89502009c6c07ff714a1224b573cbba334335e\", \"content_browsertests\": \"111ada57a083be2d628d6cf354102117b346d9fd\", \"content_unittests\": \"116dc2143605b0970da479bdf9c81c13ddca0713\", \"courgette_unittests\": \"853d8778587fdee67067bccccaaac69f4d6451f0\", \"crashpad_tests\": \"7a0f8e6f613f70c00737a1e0e0abbe0b1eb87f81\", \"cronet_tests\": \"92c7136bf72828149ded99a1077bde5fbb55ea34\", \"cronet_unittests\": \"7264acaec5f29ec22921784c1c71ce771d209f0d\", \"crypto_unittests\": \"bf030e9230c3f3b4fd1feaf333da15001844c088\", \"delayloads_unittests\": \"fd39d93636f30576c6f0d8aa347eb431b74a6659\", \"device_unittests\": \"3bb1086f36a95b527231696a099fdf6e3aeea2cd\", \"display_unittests\": \"de42da63b27a5a27e2b56546d67dbee1948078af\", \"elevation_service_unittests\": \"5e0b173d1700cc102450cd731658ae941748b285\", \"events_unittests\": \"f57acd6101efd1b854591f5608205c2555ee45e5\", \"extensions_browsertests\": \"8706efb385132ee543bab62559b7d3331869a61f\", \"extensions_unittests\": \"e915b6f3d9087aec0b0993767653415de3ae55e6\", \"filesystem_service_unittests\": \"25e6aa51f80d794f42ea2f62729ccbfaa938d994\", \"gcm_unit_tests\": \"b071cff0c6b7f87fda420e1c4348fe0857816816\", \"gcp_unittests\": \"5257b5ef788d09511169400f4a33288bbefe3a29\", \"gfx_unittests\": \"f1328fa32a6f4cb31c578c152c5799768c6e74a3\", \"gin_unittests\": \"7259f7f0c59832585ada97c6ca0470eeac7dcb82\", \"google_apis_unittests\": \"5fbd2fe316851e9bedccd492f2d2a3e65b36a214\", \"gpu_unittests\": \"1ffbbdb8fb1238f0d6efaff66d90e5f800466b4b\", \"gwp_asan_unittests\": \"6b102e4d9335c1f6241800a7558034dcf5d17124\", \"headless_browsertests\": \"4eb95d26a6587df0937244974c68e4a7665ed677\", \"headless_unittests\": \"d5fd146bde80ea1d124a051e50ee161a0ef8bb1e\", \"install_static_unittests\": \"1279ac7f9c508c934448f8e2eaa424bdaf8bedf9\", \"installer_util_unittests\": \"a767e5041193597c9e565957f5db830ac78dbae3\", \"interactive_ui_tests\": \"9b5ec0f0e531e85ab46c796a04bb9dc14011813b\", \"ipc_tests\": \"53adbb019a421c00d3a485e6af34308ce5e7ba7a\", \"jingle_unittests\": \"1de4168d55abdc007f23143cf7770afb7b50f136\", \"latency_unittests\": \"41bec815811117a82519eb9e4c3cfcaa2381e3db\", \"libjingle_xmpp_unittests\": \"d5d8bdf34e77507faaa1d41aea3c03f68e01ee2a\", \"liburlpattern_unittests\": \"d3b46ec2546283c045454e49a2aa112f8950e297\", \"media_blink_unittests\": \"3f84eb13de5b20301ff6434ce14076ff3b51aa67\", \"media_unittests\": \"f32b2f63c94b8a949992a1f7be64e41d8979381d\", \"message_center_unittests\": \"0b28d36a71db2b2395a8d34dd75cca4a1981b1d4\", \"midi_unittests\": \"f5530a0392942a800ff1db9dc955bd377ded85cd\", \"mojo_core_unittests\": \"d5191911cbf3f3b3f97c25abaa9be4e9a473ea82\", \"mojo_unittests\": \"ad3e00c89eb70fa3fa9d318f460a618eebee37c3\", \"nacl_loader_unittests\": \"19316933287951b3c43a5cfc1599da19b7c84101\", \"native_theme_unittests\": \"883bfe11c80ae9fef1736efe984976584959ed66\", \"net_unittests\": \"7c649bc24fee8ac5d69e50ee24186ad364d08b98\", \"notification_helper_unittests\": \"924701d206fc097063c62c1aeee27ea1d28e9f6d\", \"pdf_unittests\": \"7dcb7630a2aaf115fd8c7dc26636d8343286ddfa\", \"perfetto_unittests\": \"c8f84016658b36689c6c78d3e23e4a33f11499a6\", \"ppapi_unittests\": \"9f44e329bd9979e699b779088b6b2c09171df8cb\", \"printing_unittests\": \"cf0a144b903a8ea9f07949ab145d6ae2411870a1\", \"remoting_unittests\": \"bd581a6bc6bf1711233dc3bd02bf8ed4a587ffed\", \"sbox_integration_tests\": \"c570856925d59b43869ae38932ee32322ebee7fb\", \"sbox_unittests\": \"9e81a0f6533bb11d314f046736673f8c2c70fafc\", \"sbox_validation_tests\": \"c4c0c79337def2aeb065af89092006b488733831\", \"service_manager_unittests\": \"208a04cb692fbb65971851e157517a4ef4e6fe13\", \"services_unittests\": \"007d37bd709924a6bd946c742192fc9ad8ede7bc\", \"setup_unittests\": \"d58f2a3b6976e067d3417e90c891a55ed1fec67e\", \"shell_dialogs_unittests\": \"c59240660d35e8e8cdbc04d658c873dacce84641\", \"skia_unittests\": \"db222b8d309b5acfdbcd5ed5220c97ffcfe2353b\", \"snapshot_unittests\": \"fba5a4e2dd7461c599813dd8ca4b3fe143fdc3cf\", \"sql_unittests\": \"02c60f17060abab4a6b2a8ead6bf7ddec13d842a\", \"storage_unittests\": \"cd3601f87a8b9d3b18fea37b6a855ca1ab6382e7\", \"sync_integration_tests\": \"db8fd0caf86dd05c2536dd856ef3175c99e975f7\", \"traffic_annotation_auditor_unittests\": \"719ba234b50acbaef038e98eaa84b4f21f895a68\", \"ui_base_unittests\": \"0fd0726c0fb4fdcd1d2a44659da7411018fb1797\", \"ui_touch_selection_unittests\": \"76e6080adcb5e9cbc50494cd7b46d63e14127076\", \"unit_tests\": \"1ac5f7713d459ce42daddf6dbbca368e95537dc2\", \"updater_tests\": \"57e317dd4562e45cdcd75cd5fa7a22de755049ad\", \"url_unittests\": \"99ca02adfc8f11d972c6f0e1b530c55b41f6a633\", \"views_unittests\": \"694891431f0e5ead4bdab568bdff968d26d0e3e4\", \"viz_unittests\": \"7eefb9fead0c8284a86d480b9f176db50598b7e9\", \"vr_common_unittests\": \"30d2bf2455b76bc286830bbb344493e744da3c9b\", \"vr_pixeltests\": \"2bff76daeb43a8901f019e184f009900823906c1\", \"weblayer_browsertests\": \"d7afa5194454c45deb3499bf440108f9ef0021a8\", \"weblayer_unittests\": \"4ed5b41bb5fcc17aa5ce938ad30331564b7b0502\", \"wm_unittests\": \"6206764176e92f58ebe82095cd530d3662728ffe\", \"wtf_unittests\": \"fa7ab899dd3b1ba8817c12251ef45bc989fad160\", \"zlib_unittests\": \"0ff49215505f0060c1f23b43c974c5837a77553a\", \"zucchini_unittests\": \"5d8eb9ac0295c1d97fbad4dd7a79661e742da33c\"}}, \"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-6-gpyc\"], \"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\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1608578198663835",
"status": "COMPLETED",
"status_changed_ts": "1608581885025180",
"tags": [
"build_address:luci.chromium.ci/win-asan/16368",
"builder:win-asan",
"buildset:commit/git/e973d3e578794483da1cf89663ea4f4b15051d7f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e973d3e578794483da1cf89663ea4f4b15051d7f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025707289226795856",
"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/8860277226619954656/+/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:509f8ceebeccfe10",
"user_agent:luci-scheduler"
],
"updated_ts": "1608581885103706",
"url": "https://ci.chromium.org/b/8860277226619954656",
"utcnow_ts": "1608581886747843"
}
}
[20:18:06.764] Invocation finished in 1h1m33.693083151s with status SUCCEEDED