[16:49:49.589] New invocation is queued and will start shortly
[16:49:50.800] Starting the invocation (attempt 1)
[16:49:50.831] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:49:50.831] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:49:50.831] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9017925181251888384",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"081eba20f4807fde34be230faf4c778b5853f0a0\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9017925181251888384",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/081eba20f4807fde34be230faf4c778b5853f0a0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/081eba20f4807fde34be230faf4c778b5853f0a0",
"gitiles_ref:refs/heads/master"
]
}
[16:49:52.026] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1615999790977011",
"id": "8852495119134567840",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"081eba20f4807fde34be230faf4c778b5853f0a0\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615999791820578",
"tags": [
"build_address:luci.chromium.ci/win-asan/17837",
"builder:win-asan",
"buildset:commit/git/081eba20f4807fde34be230faf4c778b5853f0a0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/081eba20f4807fde34be230faf4c778b5853f0a0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9017925181251888384",
"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/8852495119134567840/+/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": "1615999791820704",
"url": "https://ci.chromium.org/b/8852495119134567840",
"utcnow_ts": "1615999792019533"
}
}
[16:49:52.026] Task URL: https://ci.chromium.org/b/8852495119134567840
[16:49:52.026] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:2:0) after 1m0s
[16:50:05.951] Received PubSub notification, asking Buildbucket for the build status
[16:50:06.072] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:52.192] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:2:0)
[16:50:52.192] Timer tick, asking Buildbucket for the build status
[16:50:52.279] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:52.279] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:4:0) after 1m0s
[16:51:52.458] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:4:0)
[16:51:52.458] Timer tick, asking Buildbucket for the build status
[16:51:52.556] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:52.556] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:5:0) after 1m0s
[16:52:52.728] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:5:0)
[16:52:52.728] Timer tick, asking Buildbucket for the build status
[16:52:52.799] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:52.799] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:6:0) after 1m0s
[16:53:52.992] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:6:0)
[16:53:52.992] Timer tick, asking Buildbucket for the build status
[16:53:53.058] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:53.058] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:7:0) after 1m0s
[16:54:53.148] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:7:0)
[16:54:53.148] Timer tick, asking Buildbucket for the build status
[16:54:53.237] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:53.237] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:8:0) after 1m0s
[16:55:53.301] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:8:0)
[16:55:53.301] Timer tick, asking Buildbucket for the build status
[16:55:53.392] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:53.392] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:9:0) after 1m0s
[16:56:53.482] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:9:0)
[16:56:53.482] Timer tick, asking Buildbucket for the build status
[16:56:53.544] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:53.544] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:10:0) after 1m0s
[16:57:53.666] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:10:0)
[16:57:53.666] Timer tick, asking Buildbucket for the build status
[16:57:53.741] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:53.741] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:11:0) after 1m0s
[16:58:53.804] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:11:0)
[16:58:53.804] Timer tick, asking Buildbucket for the build status
[16:58:53.900] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:53.900] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:12:0) after 1m0s
[16:59:53.966] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:12:0)
[16:59:53.966] Timer tick, asking Buildbucket for the build status
[16:59:54.038] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:54.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:13:0) after 1m0s
[17:00:54.057] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:13:0)
[17:00:54.057] Timer tick, asking Buildbucket for the build status
[17:00:54.234] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:54.234] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:14:0) after 1m0s
[17:01:54.291] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:14:0)
[17:01:54.291] Timer tick, asking Buildbucket for the build status
[17:01:54.537] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:54.537] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:15:0) after 1m0s
[17:02:54.557] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:15:0)
[17:02:54.557] Timer tick, asking Buildbucket for the build status
[17:02:54.625] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:54.625] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:16:0) after 1m0s
[17:03:54.701] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:16:0)
[17:03:54.701] Timer tick, asking Buildbucket for the build status
[17:03:54.884] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:54.884] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:17:0) after 1m0s
[17:04:54.952] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:17:0)
[17:04:54.952] Timer tick, asking Buildbucket for the build status
[17:04:55.276] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:55.276] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:18:0) after 1m0s
[17:05:55.319] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:18:0)
[17:05:55.319] Timer tick, asking Buildbucket for the build status
[17:05:55.660] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:55.660] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:19:0) after 1m0s
[17:06:55.719] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:19:0)
[17:06:55.719] Timer tick, asking Buildbucket for the build status
[17:06:56.081] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:56.081] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:20:0) after 1m0s
[17:07:56.121] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:20:0)
[17:07:56.121] Timer tick, asking Buildbucket for the build status
[17:07:56.404] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:56.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:21:0) after 1m0s
[17:08:56.430] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:21:0)
[17:08:56.430] Timer tick, asking Buildbucket for the build status
[17:08:56.521] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:56.521] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:22:0) after 1m0s
[17:09:56.559] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:22:0)
[17:09:56.559] Timer tick, asking Buildbucket for the build status
[17:09:56.634] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:56.634] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:23:0) after 1m0s
[17:10:56.603] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:23:0)
[17:10:56.603] Timer tick, asking Buildbucket for the build status
[17:10:56.703] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:56.703] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:24:0) after 1m0s
[17:11:56.751] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:24:0)
[17:11:56.751] Timer tick, asking Buildbucket for the build status
[17:11:56.836] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:56.836] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:25:0) after 1m0s
[17:12:56.837] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:25:0)
[17:12:56.837] Timer tick, asking Buildbucket for the build status
[17:12:56.953] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:56.953] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:26:0) after 1m0s
[17:13:56.984] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:26:0)
[17:13:56.984] Timer tick, asking Buildbucket for the build status
[17:13:57.142] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:57.142] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:27:0) after 1m0s
[17:14:57.204] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:27:0)
[17:14:57.204] Timer tick, asking Buildbucket for the build status
[17:14:57.452] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:57.452] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:28:0) after 1m0s
[17:15:57.496] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:28:0)
[17:15:57.496] Timer tick, asking Buildbucket for the build status
[17:15:57.581] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:57.581] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:29:0) after 1m0s
[17:16:57.611] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:29:0)
[17:16:57.611] Timer tick, asking Buildbucket for the build status
[17:16:57.749] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:57.749] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:30:0) after 1m0s
[17:17:57.731] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:30:0)
[17:17:57.731] Timer tick, asking Buildbucket for the build status
[17:17:57.809] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:57.809] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:31:0) after 1m0s
[17:18:57.787] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:31:0)
[17:18:57.787] Timer tick, asking Buildbucket for the build status
[17:18:57.864] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:57.864] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:32:0) after 1m0s
[17:19:57.905] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:32:0)
[17:19:57.905] Timer tick, asking Buildbucket for the build status
[17:19:57.997] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:57.997] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:33:0) after 1m0s
[17:20:57.987] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:33:0)
[17:20:57.987] Timer tick, asking Buildbucket for the build status
[17:20:58.073] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:58.073] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:34:0) after 1m0s
[17:21:58.087] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:34:0)
[17:21:58.087] Timer tick, asking Buildbucket for the build status
[17:21:58.309] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:58.309] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:35:0) after 1m0s
[17:22:58.327] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:35:0)
[17:22:58.327] Timer tick, asking Buildbucket for the build status
[17:22:58.406] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:58.406] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:36:0) after 1m0s
[17:23:58.506] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:36:0)
[17:23:58.506] Timer tick, asking Buildbucket for the build status
[17:23:58.598] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:58.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:37:0) after 1m0s
[17:24:58.648] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:37:0)
[17:24:58.648] Timer tick, asking Buildbucket for the build status
[17:24:58.753] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:58.753] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:38:0) after 1m0s
[17:25:58.777] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:38:0)
[17:25:58.777] Timer tick, asking Buildbucket for the build status
[17:25:58.857] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:58.857] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:39:0) after 1m0s
[17:26:58.860] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:39:0)
[17:26:58.860] Timer tick, asking Buildbucket for the build status
[17:26:58.944] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:58.944] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:40:0) after 1m0s
[17:27:58.968] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:40:0)
[17:27:58.968] Timer tick, asking Buildbucket for the build status
[17:27:59.136] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:59.136] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:41:0) after 1m0s
[17:28:59.177] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:41:0)
[17:28:59.177] Timer tick, asking Buildbucket for the build status
[17:28:59.673] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:59.673] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:42:0) after 1m0s
[17:29:59.707] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:42:0)
[17:29:59.707] Timer tick, asking Buildbucket for the build status
[17:29:59.874] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:59.874] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:43:0) after 1m0s
[17:30:59.933] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:43:0)
[17:30:59.933] Timer tick, asking Buildbucket for the build status
[17:31:00.095] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:00.095] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:44:0) after 1m0s
[17:32:00.139] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:44:0)
[17:32:00.139] Timer tick, asking Buildbucket for the build status
[17:32:00.500] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:00.500] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:45:0) after 1m0s
[17:33:00.573] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:45:0)
[17:33:00.573] Timer tick, asking Buildbucket for the build status
[17:33:00.649] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:00.649] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:46:0) after 1m0s
[17:34:00.701] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:46:0)
[17:34:00.701] Timer tick, asking Buildbucket for the build status
[17:34:00.798] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:00.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:47:0) after 1m0s
[17:35:00.831] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:47:0)
[17:35:00.831] Timer tick, asking Buildbucket for the build status
[17:35:00.970] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:00.970] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:48:0) after 1m0s
[17:36:00.975] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:48:0)
[17:36:00.976] Timer tick, asking Buildbucket for the build status
[17:36:01.066] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:01.066] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:49:0) after 1m0s
[17:37:01.196] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:49:0)
[17:37:01.196] Timer tick, asking Buildbucket for the build status
[17:37:01.483] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:01.483] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:50:0) after 1m0s
[17:38:01.565] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:50:0)
[17:38:01.565] Timer tick, asking Buildbucket for the build status
[17:38:01.672] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:01.672] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:51:0) after 1m0s
[17:39:01.812] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:51:0)
[17:39:01.812] Timer tick, asking Buildbucket for the build status
[17:39:02.037] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:02.037] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:52:0) after 1m0s
[17:40:02.064] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:52:0)
[17:40:02.064] Timer tick, asking Buildbucket for the build status
[17:40:02.388] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:02.388] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:53:0) after 1m0s
[17:41:02.407] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:53:0)
[17:41:02.407] Timer tick, asking Buildbucket for the build status
[17:41:02.608] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:02.608] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:54:0) after 1m0s
[17:42:02.658] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:54:0)
[17:42:02.659] Timer tick, asking Buildbucket for the build status
[17:42:02.761] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:02.761] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:55:0) after 1m0s
[17:43:02.793] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:55:0)
[17:43:02.793] Timer tick, asking Buildbucket for the build status
[17:43:02.901] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:02.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:56:0) after 1m0s
[17:44:02.912] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:56:0)
[17:44:02.912] Timer tick, asking Buildbucket for the build status
[17:44:02.999] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:02.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:57:0) after 1m0s
[17:45:03.139] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:57:0)
[17:45:03.139] Timer tick, asking Buildbucket for the build status
[17:45:03.228] Build 8852495119134567840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:03.228] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017925181251888384:58:0) after 1m0s
[17:45:29.093] Received PubSub notification, asking Buildbucket for the build status
[17:45:29.371] Build 8852495119134567840: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[17:45:29.371] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1616003128158875",
"created_by": "project:chromium",
"created_ts": "1615999790977011",
"id": "8852495119134567840",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"081eba20f4807fde34be230faf4c778b5853f0a0\"}}",
"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\": \"081eba20f4807fde34be230faf4c778b5853f0a0\", \"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-0-rwcz\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17837, \"clang_revision\": \"llvmorg-13-init-3462-gfe5c2c3c-2\", \"got_angle_revision\": \"b3394db36779b618b824d91f52cfd3dc3136c21f\", \"got_dawn_revision\": \"3209df5a9cc2de5415688653d1142b1adda554db\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"081eba20f4807fde34be230faf4c778b5853f0a0\", \"got_revision_cp\": \"refs/heads/master@{#863807}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"a98fb3f8380a3a5c9ae3e0175634ba88c85a434f\", \"got_v8_revision\": \"07c60e0c75db7c20980bbc2aa51f3468985a60da\", \"got_v8_revision_cp\": \"refs/heads/9.1.142@{#1}\", \"got_webrtc_revision\": \"417361423e789b798185d324241f4ac9bfd09066\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33487}\", \"is_cached\": true, \"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\": \"081eba20f4807fde34be230faf4c778b5853f0a0\", \"swarm_hashes_refs/heads/master(at){#863807}_without_patch\": {\"absl_hardening_tests\": \"e17fc02e8c3753caf0d5fc92198c075a194e839c\", \"accessibility_unittests\": \"bd7e80a8c14d4f0b0faba5b2f1c9b65a1773ec72\", \"angle_unittests\": \"b475ad7a568eaa4d409f80379de7b37ae1325fee\", \"app_shell_unittests\": \"c10fa9d45adb997ef0a372c9d45aac4d028e28b9\", \"aura_unittests\": \"826dda708e7d3a3656d63a4d8ccdaef5cec1c722\", \"base_unittests\": \"bde75a85f0b1dc7adf783ded4dcb1b4aaeb4b3b5\", \"base_util_unittests\": \"67045f8d3fb98bca6938d08e47bb8cbdc4cc1393\", \"blink_common_unittests\": \"ce2d5c15715488ac93e66d37656eeecb7adc37ae\", \"blink_fuzzer_unittests\": \"a30951fb868b910b8724712e9492c472b1f7e568\", \"blink_heap_unittests\": \"d436cadd9d2edec297a973bcce13415164d165ef\", \"blink_platform_unittests\": \"22f519bfcdbe77dafb68b0fadf9636e3cb281e34\", \"blink_unittests\": \"4504fabdd440ae86fb625851dcf49e8afb4921c2\", \"boringssl_crypto_tests\": \"ac06724533a67f062d5547294a8d5f5c7fc2e003\", \"boringssl_ssl_tests\": \"07a49606971d56e09da8c598337246bd08df711c\", \"browser_switcher_bho_unittests\": \"bc1f1179b5de779155f307cb4de8e1dfbb77784c\", \"browser_tests\": \"c72d9684f516c316c3b943fcbd34c702f6c691f6\", \"capture_unittests\": \"6c8e59ec38b7ea21ecd11f555cea5fcb890490df\", \"cast_unittests\": \"b80c3f2bf9212e26d47f05cf8e41e7e827d1b27b\", \"cc_unittests\": \"e3eba33edd71b9285ae076571015741d44be1416\", \"chrome_app_unittests\": \"b3b82ebaf75a25a46813bbb7f3a8d1e2aacb7564\", \"chrome_cleaner_unittests\": \"e2f3aeab06b5a03c16a4dc764d620be281265236\", \"chrome_elf_unittests\": \"bb199271b2850046bfe49e88c0adc596516c766a\", \"chromedriver_unittests\": \"d05916a8e9ec6b77f4f38a462b4fdc98f9f0441d\", \"color_unittests\": \"4a3eb5f65bb517b7e496658110d2d683a64171b3\", \"components_browsertests\": \"8244240767c895fe1c3385b30be2a31274980f5a\", \"components_unittests\": \"b4107b95a1580c2cd93d446e269c279cd3126628\", \"compositor_unittests\": \"791f5cb0b6f30e847dc3849fad5c6bc1ef21a11a\", \"content_browsertests\": \"c9eddaa8e246b4ef8a76e7578927fc55b0c0af1c\", \"content_unittests\": \"7fc75c91c82ee5bf2e0319256096d6de605ecf4a\", \"courgette_unittests\": \"ce3d791441c82bfae6938209459f4089a27830d6\", \"crashpad_tests\": \"29b07103947099cc3f745ca7e08e80418434882c\", \"cronet_tests\": \"07cf93f11c5bb0a3d7fa8313bbead0bdadc0d708\", \"cronet_unittests\": \"245cd72302c1bd10ea4da346fa34ac416bbedf96\", \"crypto_unittests\": \"9e845b865d613dca14391295e91911c7bd4c6acf\", \"delayloads_unittests\": \"c15ad5494a004115201849ced1b7f64ce5a1fe5d\", \"device_unittests\": \"ccadcbc6fd7619cb7eddad8a6317a0d23598b96e\", \"display_unittests\": \"75288860d8226438e146452920fe7a93282c2adf\", \"elevation_service_unittests\": \"4280fca05149dd432154cbb2dd1ef892ab859e38\", \"events_unittests\": \"2ef53f581fe84d6b7d95f41dcf3470bd2c841cff\", \"extensions_browsertests\": \"258fd814993e3b539488e40dacd4f49b32385952\", \"extensions_unittests\": \"7ec973245bc23b331417ee5628d90b86b31d4b15\", \"filesystem_service_unittests\": \"017d8a46f5fb28834428a32991795f4246801ee0\", \"gcm_unit_tests\": \"8789aaad6204ff044a2036a9bef8abeffca3cf5e\", \"gcp_unittests\": \"f0f3db8e1a287e0a9e3aafd5475c9ad68b8143ea\", \"gfx_unittests\": \"720888bc864803e9f861302d41ee9dec22097627\", \"gin_unittests\": \"e30d4b9178d926bd161438b7d013090773404c62\", \"google_apis_unittests\": \"a14a073465399406c89d99ea0495950a83a96bf7\", \"gpu_unittests\": \"71dd0563a017048c4d71a92f34794ed1a502351c\", \"gwp_asan_unittests\": \"dc3f1a3a27ff01deffc0caea306b70cfd1b07de5\", \"headless_browsertests\": \"d91e096d724fd2c34a78151d2590383c864f662f\", \"headless_unittests\": \"a55ddbbb1f4f43e135532e9353a927f4b652a0a4\", \"install_static_unittests\": \"eeefa6a64f6cd0b5cdad1c1c8434a1286fd7b375\", \"installer_util_unittests\": \"b191597f92c8f15c2635ce32e7a0d0888734dc5d\", \"interactive_ui_tests\": \"c5eac99bb7535f01ea3d670aaedfeaadb47d448b\", \"ipc_tests\": \"6ad0d4476337210922c7c8dc52f31e6b580c54ef\", \"jingle_unittests\": \"cf02de24f32948a582c3e8e28fe9840d39bd729b\", \"latency_unittests\": \"4923604117a99aa0fa73ab7a6a7343c48048a450\", \"libjingle_xmpp_unittests\": \"4003566fa139a1baa014cb7eb4cbc4d84ed5d597\", \"liburlpattern_unittests\": \"8d94c2d97d04fa41dae83f7449f4d7b61d2e3377\", \"media_blink_unittests\": \"69ae3e57dc841cd97af9690ee583862dafc0b210\", \"media_unittests\": \"ab1b5cb9467dd73ab74ce37851f7ec7d7f712a07\", \"message_center_unittests\": \"c048249ea09b6e14d00fcbafdedc2f9369b0d36d\", \"midi_unittests\": \"fa2b86ca5d722ff9f4a9df3a9adebdef7a2dbb1f\", \"mojo_core_unittests\": \"97ee597ca6fd1d9c523c898984c7e7107755c6ae\", \"mojo_unittests\": \"3ea71ebe51ce2b0c43668ef71bc8befde763c463\", \"nacl_loader_unittests\": \"d05e4837516f29c67c882b861bf2e7abf2b289ba\", \"native_theme_unittests\": \"6e9c4b0090e20ebe6c2f9e07b7a55648d3072f25\", \"net_unittests\": \"9fc3d1118b80446725574c9ab908eaf04633007b\", \"notification_helper_unittests\": \"395d97e1e4e173656f28e398141144f4fcb3e07a\", \"pdf_unittests\": \"7524aed4882e1e49f14d95b846630bc2eda97317\", \"perfetto_unittests\": \"ea1d718b235c520f7e658d3b575303323350f95f\", \"ppapi_unittests\": \"494c188c71bbcf1969e3f342cf2b5ab00e43a7d3\", \"printing_unittests\": \"6a905d85ed499a08a3d932a5083044d755a55ae4\", \"remoting_unittests\": \"43e334c2f8d5a84800a7c5974a186fa731d5222e\", \"sbox_integration_tests\": \"a875b140ca8a71e9fbd3040d7a5fea2aff6b4b6b\", \"sbox_unittests\": \"43090ce5e9ae5c05a8af529e87e4f44aae493bc3\", \"sbox_validation_tests\": \"2e560c6bfedc3f6a948ae446c1306c3d16d32af0\", \"service_manager_unittests\": \"fc544104f01b84acabeba05898613d52a6f07b45\", \"services_unittests\": \"9940a20db5135b476d9e8fa30e5ca47c6eec9e5a\", \"setup_unittests\": \"8f864da8a0d5318d82e1e8c1502038ba79aa0c1f\", \"shell_dialogs_unittests\": \"f715cf67b7a6346cdb29421d824d73623ef53caa\", \"skia_unittests\": \"9c77309be4ef07debf2bac8589e22638b34827ab\", \"snapshot_unittests\": \"5e601e920b4096996982968971f9ae885958bb56\", \"sql_unittests\": \"8f141c340b0c3ccd1cc607370a1fd247e9c40134\", \"storage_unittests\": \"512f1b68c2f0604cccac6f8e9262e33495035601\", \"sync_integration_tests\": \"a2c5c66b80ecb1642df682c8884ce0c2681bf2b8\", \"traffic_annotation_auditor_unittests\": \"77cc2f4288581770548f7af30f06e4e371b63dce\", \"ui_base_unittests\": \"4ef8953838e8d1fc0d331e8e7c2af489f290de83\", \"ui_touch_selection_unittests\": \"b4666300f46082532ea379ddef5bfba683424243\", \"unit_tests\": \"fd5e82c636257cdc83eb9131fe46ae73f0fd6668\", \"updater_tests\": \"79828c37a25ad89a925f603933b50d892840bc75\", \"url_unittests\": \"48eef7dc1da2c0f31bdd9d115b6971c3981ccf31\", \"views_unittests\": \"f399fb438e189fe5bcac683ae13a8e45a8a5739c\", \"viz_unittests\": \"c9da6edfdd67e0c8802fea1a962ecd9c2ee60d0b\", \"vr_common_unittests\": \"90b7db5aedaaf75dbe1bb98167aab888fc1efa05\", \"vr_pixeltests\": \"4bcca54a454d60229d9ba9a41580f972522340f5\", \"weblayer_browsertests\": \"a208368722634e9ecea12d953d804bcede278d64\", \"weblayer_unittests\": \"3934123641cc9a64db938fb9390b1e8b319ca50b\", \"wm_unittests\": \"c45d92a34ce761b760192539daae83a3ab2c0d06\", \"wtf_unittests\": \"63e357165ff46329f5d2e6d33c1240309607c04b\", \"zlib_unittests\": \"7853b677b7c5bbaa42bccf9f3c48523bdb25c3e9\", \"zucchini_unittests\": \"13242fe8d5ecee574ac83146d3b6c88d3591bb83\"}}, \"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-0-rwcz\"], \"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\": [\"5754-8c4f760\"], \"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": "1615999800883698",
"status": "COMPLETED",
"status_changed_ts": "1616003128453784",
"tags": [
"build_address:luci.chromium.ci/win-asan/17837",
"builder:win-asan",
"buildset:commit/git/081eba20f4807fde34be230faf4c778b5853f0a0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/081eba20f4807fde34be230faf4c778b5853f0a0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9017925181251888384",
"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/8852495119134567840/+/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:5259e97cd3318a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1616003128457591",
"url": "https://ci.chromium.org/b/8852495119134567840",
"utcnow_ts": "1616003129351422"
}
}
[17:45:29.371] Invocation finished in 55m39.797962332s with status SUCCEEDED