[04:25:56.171] New invocation is queued and will start shortly
[04:25:57.273] Starting the invocation (attempt 1)
[04:25:57.306] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:25:57.307] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:25:57.307] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019783922111076768",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"b4afef0dea851b338736a2abfb0b575588512554\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9019783922111076768",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/b4afef0dea851b338736a2abfb0b575588512554",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b4afef0dea851b338736a2abfb0b575588512554",
"gitiles_ref:refs/heads/master"
]
}
[04:25:57.980] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614227157447924",
"id": "8854353860111118560",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b4afef0dea851b338736a2abfb0b575588512554\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614227157847752",
"tags": [
"build_address:luci.chromium.ci/win-asan/17519",
"builder:win-asan",
"buildset:commit/git/b4afef0dea851b338736a2abfb0b575588512554",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b4afef0dea851b338736a2abfb0b575588512554",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019783922111076768",
"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/8854353860111118560/+/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": "1614227157847870",
"url": "https://ci.chromium.org/b/8854353860111118560",
"utcnow_ts": "1614227157971943"
}
}
[04:25:57.980] Task URL: https://ci.chromium.org/b/8854353860111118560
[04:25:57.980] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:2:0) after 1m0s
[04:25:59.720] Received PubSub notification, asking Buildbucket for the build status
[04:25:59.832] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:58.039] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:2:0)
[04:26:58.039] Timer tick, asking Buildbucket for the build status
[04:26:58.124] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:58.124] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:4:0) after 1m0s
[04:27:58.203] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:4:0)
[04:27:58.203] Timer tick, asking Buildbucket for the build status
[04:27:58.272] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:58.272] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:5:0) after 1m0s
[04:28:58.449] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:5:0)
[04:28:58.449] Timer tick, asking Buildbucket for the build status
[04:28:58.538] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:58.538] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:6:0) after 1m0s
[04:29:58.649] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:6:0)
[04:29:58.649] Timer tick, asking Buildbucket for the build status
[04:29:58.714] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:58.714] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:7:0) after 1m0s
[04:30:58.812] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:7:0)
[04:30:58.812] Timer tick, asking Buildbucket for the build status
[04:30:58.927] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:58.927] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:8:0) after 1m0s
[04:31:59.003] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:8:0)
[04:31:59.003] Timer tick, asking Buildbucket for the build status
[04:31:59.079] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:59.079] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:9:0) after 1m0s
[04:32:59.143] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:9:0)
[04:32:59.143] Timer tick, asking Buildbucket for the build status
[04:32:59.457] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:59.457] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:10:0) after 1m0s
[04:33:59.481] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:10:0)
[04:33:59.481] Timer tick, asking Buildbucket for the build status
[04:33:59.558] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:59.558] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:11:0) after 1m0s
[04:34:59.862] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:11:0)
[04:34:59.862] Timer tick, asking Buildbucket for the build status
[04:34:59.929] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:59.929] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:12:0) after 1m0s
[04:35:59.950] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:12:0)
[04:35:59.950] Timer tick, asking Buildbucket for the build status
[04:36:00.118] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:00.118] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:13:0) after 1m0s
[04:37:00.110] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:13:0)
[04:37:00.110] Timer tick, asking Buildbucket for the build status
[04:37:00.180] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:00.180] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:14:0) after 1m0s
[04:38:00.184] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:14:0)
[04:38:00.184] Timer tick, asking Buildbucket for the build status
[04:38:00.373] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:00.373] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:15:0) after 1m0s
[04:39:00.359] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:15:0)
[04:39:00.359] Timer tick, asking Buildbucket for the build status
[04:39:00.430] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:00.430] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:16:0) after 1m0s
[04:40:00.511] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:16:0)
[04:40:00.511] Timer tick, asking Buildbucket for the build status
[04:40:00.599] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:00.599] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:17:0) after 1m0s
[04:41:00.678] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:17:0)
[04:41:00.679] Timer tick, asking Buildbucket for the build status
[04:41:00.812] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:00.812] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:18:0) after 1m0s
[04:42:00.845] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:18:0)
[04:42:00.846] Timer tick, asking Buildbucket for the build status
[04:42:01.316] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:01.316] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:19:0) after 1m0s
[04:43:01.523] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:19:0)
[04:43:01.523] Timer tick, asking Buildbucket for the build status
[04:43:01.591] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:01.591] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:20:0) after 1m0s
[04:44:01.602] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:20:0)
[04:44:01.602] Timer tick, asking Buildbucket for the build status
[04:44:01.739] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:01.739] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:21:0) after 1m0s
[04:45:01.766] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:21:0)
[04:45:01.766] Timer tick, asking Buildbucket for the build status
[04:45:01.998] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:01.998] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:22:0) after 1m0s
[04:46:01.996] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:22:0)
[04:46:01.996] Timer tick, asking Buildbucket for the build status
[04:46:02.164] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:02.164] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:23:0) after 1m0s
[04:47:02.181] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:23:0)
[04:47:02.181] Timer tick, asking Buildbucket for the build status
[04:47:02.268] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:02.268] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:24:0) after 1m0s
[04:48:02.304] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:24:0)
[04:48:02.304] Timer tick, asking Buildbucket for the build status
[04:48:02.438] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:02.438] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:25:0) after 1m0s
[04:49:02.449] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:25:0)
[04:49:02.449] Timer tick, asking Buildbucket for the build status
[04:49:02.560] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:02.560] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:26:0) after 1m0s
[04:50:02.703] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:26:0)
[04:50:02.703] Timer tick, asking Buildbucket for the build status
[04:50:02.837] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:02.837] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:27:0) after 1m0s
[04:51:02.869] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:27:0)
[04:51:02.869] Timer tick, asking Buildbucket for the build status
[04:51:03.650] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:03.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:28:0) after 1m0s
[04:52:03.726] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:28:0)
[04:52:03.726] Timer tick, asking Buildbucket for the build status
[04:52:03.828] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:03.828] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:29:0) after 1m0s
[04:53:03.909] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:29:0)
[04:53:03.909] Timer tick, asking Buildbucket for the build status
[04:53:03.993] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:03.993] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:30:0) after 1m0s
[04:54:04.050] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:30:0)
[04:54:04.050] Timer tick, asking Buildbucket for the build status
[04:54:04.516] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:04.516] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:31:0) after 1m0s
[04:55:04.553] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:31:0)
[04:55:04.553] Timer tick, asking Buildbucket for the build status
[04:55:04.721] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:04.721] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:32:0) after 1m0s
[04:56:04.735] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:32:0)
[04:56:04.735] Timer tick, asking Buildbucket for the build status
[04:56:05.070] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:05.071] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:33:0) after 1m0s
[04:57:05.096] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:33:0)
[04:57:05.096] Timer tick, asking Buildbucket for the build status
[04:57:05.455] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:05.455] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:34:0) after 1m0s
[04:58:05.477] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:34:0)
[04:58:05.477] Timer tick, asking Buildbucket for the build status
[04:58:05.565] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:05.565] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:35:0) after 1m0s
[04:59:05.674] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:35:0)
[04:59:05.674] Timer tick, asking Buildbucket for the build status
[04:59:05.831] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:05.831] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:36:0) after 1m0s
[05:00:05.816] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:36:0)
[05:00:05.816] Timer tick, asking Buildbucket for the build status
[05:00:05.969] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:05.969] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:37:0) after 1m0s
[05:01:06.751] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:37:0)
[05:01:06.751] Timer tick, asking Buildbucket for the build status
[05:01:06.897] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:06.897] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:38:0) after 1m0s
[05:02:06.964] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:38:0)
[05:02:06.964] Timer tick, asking Buildbucket for the build status
[05:02:07.335] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:07.335] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:39:0) after 1m0s
[05:03:07.391] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:39:0)
[05:03:07.391] Timer tick, asking Buildbucket for the build status
[05:03:07.490] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:07.490] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:40:0) after 1m0s
[05:04:07.520] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:40:0)
[05:04:07.521] Timer tick, asking Buildbucket for the build status
[05:04:07.602] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:07.602] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:41:0) after 1m0s
[05:05:07.673] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:41:0)
[05:05:07.673] Timer tick, asking Buildbucket for the build status
[05:05:07.917] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:07.917] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:42:0) after 1m0s
[05:06:07.948] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:42:0)
[05:06:07.948] Timer tick, asking Buildbucket for the build status
[05:06:08.075] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:08.075] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:43:0) after 1m0s
[05:07:08.142] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:43:0)
[05:07:08.142] Timer tick, asking Buildbucket for the build status
[05:07:08.204] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:08.204] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:44:0) after 1m0s
[05:08:08.223] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:44:0)
[05:08:08.223] Timer tick, asking Buildbucket for the build status
[05:08:08.306] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:08.306] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:45:0) after 1m0s
[05:09:08.323] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:45:0)
[05:09:08.323] Timer tick, asking Buildbucket for the build status
[05:09:08.768] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:08.768] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:46:0) after 1m0s
[05:10:08.766] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:46:0)
[05:10:08.766] Timer tick, asking Buildbucket for the build status
[05:10:08.887] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:08.887] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:47:0) after 1m0s
[05:11:08.993] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:47:0)
[05:11:08.993] Timer tick, asking Buildbucket for the build status
[05:11:09.089] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:09.089] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:48:0) after 1m0s
[05:12:09.147] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:48:0)
[05:12:09.147] Timer tick, asking Buildbucket for the build status
[05:12:09.247] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:09.247] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:49:0) after 1m0s
[05:13:09.225] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:49:0)
[05:13:09.225] Timer tick, asking Buildbucket for the build status
[05:13:09.356] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:09.356] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:50:0) after 1m0s
[05:14:09.424] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:50:0)
[05:14:09.424] Timer tick, asking Buildbucket for the build status
[05:14:09.518] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:09.518] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:51:0) after 1m0s
[05:15:09.645] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:51:0)
[05:15:09.645] Timer tick, asking Buildbucket for the build status
[05:15:09.775] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:09.775] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:52:0) after 1m0s
[05:16:09.802] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:52:0)
[05:16:09.802] Timer tick, asking Buildbucket for the build status
[05:16:09.895] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:09.895] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:53:0) after 1m0s
[05:17:09.923] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:53:0)
[05:17:09.923] Timer tick, asking Buildbucket for the build status
[05:17:10.009] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:10.009] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:54:0) after 1m0s
[05:18:10.047] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:54:0)
[05:18:10.047] Timer tick, asking Buildbucket for the build status
[05:18:10.127] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:10.127] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:55:0) after 1m0s
[05:19:10.145] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:55:0)
[05:19:10.145] Timer tick, asking Buildbucket for the build status
[05:19:10.747] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:10.747] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:56:0) after 1m0s
[05:20:10.787] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:56:0)
[05:20:10.787] Timer tick, asking Buildbucket for the build status
[05:20:10.876] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:10.876] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:57:0) after 1m0s
[05:21:10.938] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:57:0)
[05:21:10.938] Timer tick, asking Buildbucket for the build status
[05:21:11.036] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:11.036] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:58:0) after 1m0s
[05:22:11.079] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:58:0)
[05:22:11.079] Timer tick, asking Buildbucket for the build status
[05:22:11.168] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:11.168] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:59:0) after 1m0s
[05:23:11.242] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:59:0)
[05:23:11.242] Timer tick, asking Buildbucket for the build status
[05:23:11.727] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:11.727] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:60:0) after 1m0s
[05:24:11.784] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:60:0)
[05:24:11.784] Timer tick, asking Buildbucket for the build status
[05:24:11.902] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:11.902] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:61:0) after 1m0s
[05:25:11.901] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:61:0)
[05:25:11.901] Timer tick, asking Buildbucket for the build status
[05:25:11.978] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:11.978] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:62:0) after 1m0s
[05:26:11.997] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:62:0)
[05:26:11.997] Timer tick, asking Buildbucket for the build status
[05:26:12.106] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:12.106] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:63:0) after 1m0s
[05:27:12.127] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:63:0)
[05:27:12.127] Timer tick, asking Buildbucket for the build status
[05:27:12.457] Build 8854353860111118560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:12.457] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019783922111076768:64:0) after 1m0s
[05:28:02.293] Received PubSub notification, asking Buildbucket for the build status
[05:28:02.385] Build 8854353860111118560: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:28:02.385] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614230880555373",
"created_by": "project:chromium",
"created_ts": "1614227157447924",
"id": "8854353860111118560",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b4afef0dea851b338736a2abfb0b575588512554\"}}",
"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\": \"b4afef0dea851b338736a2abfb0b575588512554\", \"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-3-xpf4\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17519, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-2\", \"got_angle_revision\": \"9b1c569b14e90765cdd7c07e449400e88f1d6c45\", \"got_dawn_revision\": \"5eb496b863da5b40dc5c9a713c8ca1e413811f35\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"b4afef0dea851b338736a2abfb0b575588512554\", \"got_revision_cp\": \"refs/heads/master@{#857536}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"349abccec8f7ebd1259de11965d58790a76b0ba4\", \"got_v8_revision\": \"bd0108b4c88e0d6f2350cb79b5f363fbd02f3eb7\", \"got_v8_revision_cp\": \"refs/heads/9.0.257@{#1}\", \"got_webrtc_revision\": \"cd0373f013b5c9672c05e79a48bb1e9fb5f50fa4\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33333}\", \"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\": \"b4afef0dea851b338736a2abfb0b575588512554\", \"swarm_hashes_refs/heads/master(at){#857536}_without_patch\": {\"absl_hardening_tests\": \"9a85e7c3b08e4569fec1f8b11e323bcc17bf58d8\", \"accessibility_unittests\": \"03dbbdbd3014cfe3aa5f03851aa19296366b0c85\", \"angle_unittests\": \"19e55840f5496126ff58acb342f972f0beb6951b\", \"app_shell_unittests\": \"5c94afc3f4dc1382d4a9a882398936962d68a78b\", \"aura_unittests\": \"e6cbc80523982db9cccbb2c5cc6a70992542c031\", \"base_unittests\": \"9c417b0c5ec750082b588e62c9c43bc237320368\", \"base_util_unittests\": \"1e877c18b13190c8c2a342abdf8b40749143f355\", \"blink_common_unittests\": \"624528a7da9a168bf73aa6f8458219be54b86e6f\", \"blink_fuzzer_unittests\": \"f28726bc328970c61abf330bb4152204cd345ffe\", \"blink_heap_unittests\": \"c88fbc0394b5041b68c1c6408c0509e090f31995\", \"blink_platform_unittests\": \"07bb3f1ff67822f9337fc2cd23406c6f4d16e20a\", \"blink_unittests\": \"b939d88be6a97c3d92d96d56934a53cd775a8654\", \"boringssl_crypto_tests\": \"17c23890eff6f3f861d3322856ef0ec64272dd61\", \"boringssl_ssl_tests\": \"a8a06260158e1d3803527ba275f179661eb48a2e\", \"browser_switcher_bho_unittests\": \"39d51236b5cdd553df7ae1eab8f6ca660fa64033\", \"browser_tests\": \"0751ac6fc9215654fea0aac60f2cda694d3b4caa\", \"capture_unittests\": \"27acb23c2f8d880a5ab1684f898411864c523604\", \"cast_unittests\": \"d2de187e85aa527b8a71553422b91a3b93eab004\", \"cc_unittests\": \"5d4e910eb114a2bc7648ea54c8017e6defac28cb\", \"chrome_app_unittests\": \"c1cc58bc085072a28d825fb68ae70062ab94169c\", \"chrome_cleaner_unittests\": \"bc85a870d613fc9492bde2a127712562121b0481\", \"chrome_elf_unittests\": \"5fa843d4c86ebbc6abe4b6e575e7b32204d3f9a4\", \"chromedriver_unittests\": \"a7ca23b182edd21214761a8b2938bb9a28a6bccd\", \"color_unittests\": \"6dd1b2a93382dfe8951e965d44f795b7dcca4a41\", \"components_browsertests\": \"5db8e0ccd0b25cad8606d9fa50c21fd56382f9b5\", \"components_unittests\": \"fe87052eb33161d78cd1c64877a2ce300d795bf8\", \"compositor_unittests\": \"867805cd5f6a1d2f7e5a87424142f4bbcf4cb6cb\", \"content_browsertests\": \"658b69fd1bf44ea241d9256a6a7053531e71683c\", \"content_unittests\": \"0d76c6081ebb597b76bef778f5fa21b6dd62b3af\", \"courgette_unittests\": \"a781a2d2bc533def1318be8a4b5284a6c61f47cb\", \"crashpad_tests\": \"c560f579c584f84fb82d5a0be7735de9fcc9df6d\", \"cronet_tests\": \"5a30301395f38c09248acb5a780828477892fcbc\", \"cronet_unittests\": \"e2822045feeb7f20ad9c8ba19faab87e70c689e4\", \"crypto_unittests\": \"c301f0136a93c82821b51d3c76175b929ac42a75\", \"delayloads_unittests\": \"e3264a700f6fab5fba62883e88ef451fae5cb5dd\", \"device_unittests\": \"7c03e81f299f359268960e79a08a1ff7c8271f0f\", \"display_unittests\": \"7b8e8bbf5abd5416c4ef5ef319374692311ccc11\", \"elevation_service_unittests\": \"12abf334453ebeffeb5e9f0d238f732d75d65020\", \"events_unittests\": \"c7062d004f563ae7b49ad621bb637ffd5b2528fd\", \"extensions_browsertests\": \"b4cead4070378878fa735f622b94f46e2d2e5064\", \"extensions_unittests\": \"78372ab96b8de6be89a778e8ea1dbf9a70e18aa7\", \"filesystem_service_unittests\": \"dba449c72de705622728b8a93153dc5bba86f295\", \"gcm_unit_tests\": \"f2c7bc76b6bfe9b5c7ccac350c4f80194e223022\", \"gcp_unittests\": \"7dff41acfb8dceb3935cc1474129a8d94bb56f69\", \"gfx_unittests\": \"a454dbe171d75be1ce8097942a62a8da3cb245ff\", \"gin_unittests\": \"fa862e4d29963285ffadc382e220baba761cd7de\", \"google_apis_unittests\": \"85ca265d8a6bc715b9faaed8ed8ec63f36dd7b76\", \"gpu_unittests\": \"149d278174c0634680c230b74c618c371ac24dc5\", \"gwp_asan_unittests\": \"8ba3b1f1ee3a13c7050f75f76046cb5969672c4b\", \"headless_browsertests\": \"8743808b4051cf3e0c6d63da71c61b564bde3bb9\", \"headless_unittests\": \"c04c275338bf828c4342ebaac55056cf3158b82b\", \"install_static_unittests\": \"b6ba51b9a1f8549ea790ba5b4c0ccac0aa2fe361\", \"installer_util_unittests\": \"106bf54b7998e2a6176e3aebc9cfe8adc0ed02a9\", \"interactive_ui_tests\": \"83686509d8ba294f680dc25e0e6b11113688da5d\", \"ipc_tests\": \"b03ba82d2be7d8151ad0ce881c1de71d697f6fa6\", \"jingle_unittests\": \"896008e23c570af39b8df1355c6334431f6c15fa\", \"latency_unittests\": \"13da9488abd176e6eb0d43532db9f1d16ce6abb2\", \"libjingle_xmpp_unittests\": \"00a500605bbb3868941c7515e2df60d54dc05601\", \"liburlpattern_unittests\": \"01872a637abf860f323a170eb5298c8435398420\", \"media_blink_unittests\": \"d5cbd628213b1feda8fb051dfe75a15b23f807bc\", \"media_unittests\": \"2775c091b1e62abd2510a3de1cc012df41e23bc8\", \"message_center_unittests\": \"2484b29717bc76773ce09171ad3b6aad3c51323c\", \"midi_unittests\": \"135e7ed3b5b526e09ad2ee5c6dbc54fec8077952\", \"mojo_core_unittests\": \"aa8d8fa410cf20edff5763e62ca669f7ac852448\", \"mojo_unittests\": \"0834e3fd338518a36b4ce31648c779ea97f08c49\", \"nacl_loader_unittests\": \"dcba5a08bac23bbb1168c7f6a7d21bb1505daab2\", \"native_theme_unittests\": \"dcf430f4d0cf1d5cbd0e2071e636496364529de4\", \"net_unittests\": \"39db2ee55c576aa9e8b595a2198e6664abdb695d\", \"notification_helper_unittests\": \"2089e8b3f5fedaddcb867762df40b8c2d1cfc1d1\", \"pdf_unittests\": \"fe089f10efe1d8deab0df6c2351907f9de5efa87\", \"perfetto_unittests\": \"280db6e0e453a3dd57a357724920f00233691060\", \"ppapi_unittests\": \"9fbb7589826557249eb1a691d0f640efcbe7d6d6\", \"printing_unittests\": \"c28429580ef7efbdf3a596f4247169e2e5940f65\", \"remoting_unittests\": \"97b39bb4fbb4e6592b4381bbf09e83b8f445f071\", \"sbox_integration_tests\": \"99ced40a94b33310532dda3b14e3d687af4b3c89\", \"sbox_unittests\": \"96f562460c4d78a524631e1df5448d497a840b7c\", \"sbox_validation_tests\": \"f4d5e8e38aff1218cb865b27d876d949cb6779b4\", \"service_manager_unittests\": \"5d8c4659c355f881dc2897e69b326d8e965bc818\", \"services_unittests\": \"8273365ea1c9264ad03538665b80306a43e515b4\", \"setup_unittests\": \"8edc6408fbc75fba2bc95c0ee4bc994f0456a1a5\", \"shell_dialogs_unittests\": \"7be44a2456c8c545f9f56e9d016aff873c04dae0\", \"skia_unittests\": \"619e5aa7111813f3e86618f53a33bdb3ac195754\", \"snapshot_unittests\": \"5463dc10aad2424b49cd827d03562a1c25a5158c\", \"sql_unittests\": \"261f6d936405fcaa683950bac860ea6500e42c8e\", \"storage_unittests\": \"84038750982769e2c279b7e21cfd4d19876e5404\", \"sync_integration_tests\": \"aee94196ce8c3df93f9c5b4f76adeb7f78aba7fd\", \"traffic_annotation_auditor_unittests\": \"1f729cd7df65b00ecdae9f9abae0d7fa71ee27f8\", \"ui_base_unittests\": \"f857cbae64216a102a1df19ca0a182c5f3876b89\", \"ui_touch_selection_unittests\": \"ed9308e44018169a955ef58bfe6ef9e1db3ae298\", \"unit_tests\": \"5976e17c704d3e37912045f4e4c3f43249874e59\", \"updater_tests\": \"91e5dab6d219f48c375ce32acd06b45ae947ca69\", \"url_unittests\": \"a0c047bdf81fe3bb0e9d6389694a2357d814c0e5\", \"views_unittests\": \"fb2d2cdf714c7fb6cd969d18c4db2dd09fe954a6\", \"viz_unittests\": \"774b7764f2265c48ccc7e353dfe7db732a2ba2d7\", \"vr_common_unittests\": \"48ba7a5cd616aa2f74f4bf049ab113571abbe420\", \"vr_pixeltests\": \"5fd4f68219710eb8816a6f3fa2eaabcc830cde3e\", \"weblayer_browsertests\": \"69edfb08e6b66619d6dc7a7b07e4529a113b4345\", \"weblayer_unittests\": \"217a563eee4428297e815428c01639d825ee0210\", \"wm_unittests\": \"03630a5135710ff2c5f1f4bfbe1da214134ad35d\", \"wtf_unittests\": \"950a236fc994b636cfa15a3cd948488e7be305e7\", \"zlib_unittests\": \"27f3e5742628067b466cec9c32d62a954cee60fb\", \"zucchini_unittests\": \"88c630941c357ebae2f9b9374c0b515d79bca8e5\"}}, \"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-3-xpf4\"], \"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\": [\"5712-339372b\"], \"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": "1614227159065472",
"status": "COMPLETED",
"status_changed_ts": "1614230881058784",
"tags": [
"build_address:luci.chromium.ci/win-asan/17519",
"builder:win-asan",
"buildset:commit/git/b4afef0dea851b338736a2abfb0b575588512554",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b4afef0dea851b338736a2abfb0b575588512554",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019783922111076768",
"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/8854353860111118560/+/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:51f0413c59677b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1614230881063739",
"url": "https://ci.chromium.org/b/8854353860111118560",
"utcnow_ts": "1614230882371214"
}
}
[05:28:02.385] Invocation finished in 1h2m6.229205241s with status SUCCEEDED