[00:35:45.573] New invocation is queued and will start shortly
[00:35:46.742] Starting the invocation (attempt 1)
[00:35:46.777] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:35:46.777] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:35:46.777] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9029401682045011120",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"6b58a991c2d6c0a2eb41f2b5937fe8abfe3def57\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9029401682045011120",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/6b58a991c2d6c0a2eb41f2b5937fe8abfe3def57",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6b58a991c2d6c0a2eb41f2b5937fe8abfe3def57",
"gitiles_ref:refs/heads/master"
]
}
[00:35:47.425] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1605054946888503",
"id": "8863971619970531696",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6b58a991c2d6c0a2eb41f2b5937fe8abfe3def57\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1605054947296635",
"tags": [
"build_address:luci.chromium.ci/win-asan/15580",
"builder:win-asan",
"buildset:commit/git/6b58a991c2d6c0a2eb41f2b5937fe8abfe3def57",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6b58a991c2d6c0a2eb41f2b5937fe8abfe3def57",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029401682045011120",
"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/8863971619970531696/+/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": "1605054947296746",
"url": "https://ci.chromium.org/b/8863971619970531696",
"utcnow_ts": "1605054947416863"
}
}
[00:35:47.425] Task URL: https://ci.chromium.org/b/8863971619970531696
[00:35:47.425] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:2:0) after 1m0s
[00:35:57.759] Received PubSub notification, asking Buildbucket for the build status
[00:35:57.845] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:47.519] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:2:0)
[00:36:47.519] Timer tick, asking Buildbucket for the build status
[00:36:47.862] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:47.862] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:4:0) after 1m0s
[00:37:47.858] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:4:0)
[00:37:47.858] Timer tick, asking Buildbucket for the build status
[00:37:48.002] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:48.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:5:0) after 1m0s
[00:38:48.037] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:5:0)
[00:38:48.037] Timer tick, asking Buildbucket for the build status
[00:38:48.538] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:48.538] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:6:0) after 1m0s
[00:39:48.614] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:6:0)
[00:39:48.614] Timer tick, asking Buildbucket for the build status
[00:39:48.771] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:48.771] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:7:0) after 1m0s
[00:40:48.837] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:7:0)
[00:40:48.837] Timer tick, asking Buildbucket for the build status
[00:40:48.991] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:48.991] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:8:0) after 1m0s
[00:41:49.392] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:8:0)
[00:41:49.392] Timer tick, asking Buildbucket for the build status
[00:41:49.525] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:49.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:9:0) after 1m0s
[00:42:49.723] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:9:0)
[00:42:49.723] Timer tick, asking Buildbucket for the build status
[00:42:49.939] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:49.939] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:10:0) after 1m0s
[00:43:50.013] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:10:0)
[00:43:50.013] Timer tick, asking Buildbucket for the build status
[00:43:50.111] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:50.111] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:11:0) after 1m0s
[00:44:50.170] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:11:0)
[00:44:50.170] Timer tick, asking Buildbucket for the build status
[00:44:50.237] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:50.237] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:12:0) after 1m0s
[00:45:50.345] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:12:0)
[00:45:50.345] Timer tick, asking Buildbucket for the build status
[00:45:50.447] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:50.447] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:13:0) after 1m0s
[00:46:50.631] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:13:0)
[00:46:50.631] Timer tick, asking Buildbucket for the build status
[00:46:50.766] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:50.766] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:14:0) after 1m0s
[00:47:50.790] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:14:0)
[00:47:50.790] Timer tick, asking Buildbucket for the build status
[00:47:50.924] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:50.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:15:0) after 1m0s
[00:48:50.978] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:15:0)
[00:48:50.978] Timer tick, asking Buildbucket for the build status
[00:48:51.542] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:51.542] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:16:0) after 1m0s
[00:49:51.605] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:16:0)
[00:49:51.605] Timer tick, asking Buildbucket for the build status
[00:49:51.710] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:51.710] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:17:0) after 1m0s
[00:50:51.714] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:17:0)
[00:50:51.714] Timer tick, asking Buildbucket for the build status
[00:50:52.047] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:52.047] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:18:0) after 1m0s
[00:51:52.081] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:18:0)
[00:51:52.081] Timer tick, asking Buildbucket for the build status
[00:51:52.217] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:52.217] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:19:0) after 1m0s
[00:52:52.267] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:19:0)
[00:52:52.267] Timer tick, asking Buildbucket for the build status
[00:52:52.467] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:52.467] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:20:0) after 1m0s
[00:53:52.490] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:20:0)
[00:53:52.490] Timer tick, asking Buildbucket for the build status
[00:53:52.825] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:52.825] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:21:0) after 1m0s
[00:54:52.881] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:21:0)
[00:54:52.881] Timer tick, asking Buildbucket for the build status
[00:54:53.459] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:53.459] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:22:0) after 1m0s
[00:55:53.527] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:22:0)
[00:55:53.527] Timer tick, asking Buildbucket for the build status
[00:55:54.168] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:54.168] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:23:0) after 1m0s
[00:56:54.231] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:23:0)
[00:56:54.231] Timer tick, asking Buildbucket for the build status
[00:56:54.353] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:54.353] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:24:0) after 1m0s
[00:57:54.687] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:24:0)
[00:57:54.687] Timer tick, asking Buildbucket for the build status
[00:57:55.212] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:55.212] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:25:0) after 1m0s
[00:58:55.252] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:25:0)
[00:58:55.252] Timer tick, asking Buildbucket for the build status
[00:58:55.509] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:55.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:26:0) after 1m0s
[00:59:55.582] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:26:0)
[00:59:55.582] Timer tick, asking Buildbucket for the build status
[00:59:55.734] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:55.734] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:27:0) after 1m0s
[01:00:55.821] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:27:0)
[01:00:55.821] Timer tick, asking Buildbucket for the build status
[01:00:56.017] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:56.018] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:28:0) after 1m0s
[01:01:56.166] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:28:0)
[01:01:56.166] Timer tick, asking Buildbucket for the build status
[01:01:56.318] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:56.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:29:0) after 1m0s
[01:02:56.356] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:29:0)
[01:02:56.356] Timer tick, asking Buildbucket for the build status
[01:02:56.501] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:56.501] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:30:0) after 1m0s
[01:03:56.607] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:30:0)
[01:03:56.607] Timer tick, asking Buildbucket for the build status
[01:03:56.817] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:56.817] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:31:0) after 1m0s
[01:04:56.865] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:31:0)
[01:04:56.865] Timer tick, asking Buildbucket for the build status
[01:04:57.113] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:57.113] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:32:0) after 1m0s
[01:05:57.410] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:32:0)
[01:05:57.410] Timer tick, asking Buildbucket for the build status
[01:05:57.551] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:57.551] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:33:0) after 1m0s
[01:06:57.940] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:33:0)
[01:06:57.940] Timer tick, asking Buildbucket for the build status
[01:06:58.110] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:58.110] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:34:0) after 1m0s
[01:07:58.194] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:34:0)
[01:07:58.194] Timer tick, asking Buildbucket for the build status
[01:07:58.391] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:58.391] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:35:0) after 1m0s
[01:08:58.444] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:35:0)
[01:08:58.444] Timer tick, asking Buildbucket for the build status
[01:09:00.341] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:00.341] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:36:0) after 1m0s
[01:10:00.385] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:36:0)
[01:10:00.385] Timer tick, asking Buildbucket for the build status
[01:10:00.511] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:00.511] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:37:0) after 1m0s
[01:11:00.518] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:37:0)
[01:11:00.518] Timer tick, asking Buildbucket for the build status
[01:11:00.861] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:00.861] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:38:0) after 1m0s
[01:12:00.993] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:38:0)
[01:12:00.993] Timer tick, asking Buildbucket for the build status
[01:12:01.132] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:01.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:39:0) after 1m0s
[01:13:04.248] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:39:0)
[01:13:04.248] Timer tick, asking Buildbucket for the build status
[01:13:04.981] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:04.981] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:40:0) after 1m0s
[01:14:05.195] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:40:0)
[01:14:05.195] Timer tick, asking Buildbucket for the build status
[01:14:05.626] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:05.626] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:41:0) after 1m0s
[01:15:05.752] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:41:0)
[01:15:05.752] Timer tick, asking Buildbucket for the build status
[01:15:06.570] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:06.570] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:42:0) after 1m0s
[01:16:06.673] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:42:0)
[01:16:06.673] Timer tick, asking Buildbucket for the build status
[01:16:08.350] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:08.350] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:43:0) after 1m0s
[01:17:08.426] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:43:0)
[01:17:08.426] Timer tick, asking Buildbucket for the build status
[01:17:08.551] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:08.551] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:44:0) after 1m0s
[01:18:08.577] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:44:0)
[01:18:08.577] Timer tick, asking Buildbucket for the build status
[01:18:08.819] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:08.819] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:45:0) after 1m0s
[01:19:08.844] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:45:0)
[01:19:08.844] Timer tick, asking Buildbucket for the build status
[01:19:09.504] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:09.504] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:46:0) after 1m0s
[01:20:09.509] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:46:0)
[01:20:09.509] Timer tick, asking Buildbucket for the build status
[01:20:09.690] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:09.690] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:47:0) after 1m0s
[01:21:09.726] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:47:0)
[01:21:09.726] Timer tick, asking Buildbucket for the build status
[01:21:09.864] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:09.864] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:48:0) after 1m0s
[01:22:09.962] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:48:0)
[01:22:09.962] Timer tick, asking Buildbucket for the build status
[01:22:10.168] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:10.168] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:49:0) after 1m0s
[01:23:10.889] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:49:0)
[01:23:10.889] Timer tick, asking Buildbucket for the build status
[01:23:11.068] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:11.068] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:50:0) after 1m0s
[01:24:11.092] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:50:0)
[01:24:11.092] Timer tick, asking Buildbucket for the build status
[01:24:11.485] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:11.485] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:51:0) after 1m0s
[01:25:11.559] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:51:0)
[01:25:11.559] Timer tick, asking Buildbucket for the build status
[01:25:11.681] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:11.681] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:52:0) after 1m0s
[01:26:11.670] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:52:0)
[01:26:11.670] Timer tick, asking Buildbucket for the build status
[01:26:11.811] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:11.811] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:53:0) after 1m0s
[01:27:11.810] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:53:0)
[01:27:11.810] Timer tick, asking Buildbucket for the build status
[01:27:11.967] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:11.967] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:54:0) after 1m0s
[01:28:11.991] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:54:0)
[01:28:11.991] Timer tick, asking Buildbucket for the build status
[01:28:12.455] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:12.455] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:55:0) after 1m0s
[01:29:12.485] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:55:0)
[01:29:12.485] Timer tick, asking Buildbucket for the build status
[01:29:12.624] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:12.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:56:0) after 1m0s
[01:30:12.653] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:56:0)
[01:30:12.653] Timer tick, asking Buildbucket for the build status
[01:30:12.734] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:12.734] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:57:0) after 1m0s
[01:31:12.765] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:57:0)
[01:31:12.765] Timer tick, asking Buildbucket for the build status
[01:31:13.388] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:13.388] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:58:0) after 1m0s
[01:32:13.434] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:58:0)
[01:32:13.434] Timer tick, asking Buildbucket for the build status
[01:32:13.535] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:13.536] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:59:0) after 1m0s
[01:33:13.697] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:59:0)
[01:33:13.697] Timer tick, asking Buildbucket for the build status
[01:33:13.937] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:13.937] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:60:0) after 1m0s
[01:34:13.963] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:60:0)
[01:34:13.963] Timer tick, asking Buildbucket for the build status
[01:34:14.741] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:14.741] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:61:0) after 1m0s
[01:35:14.732] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:61:0)
[01:35:14.732] Timer tick, asking Buildbucket for the build status
[01:35:14.848] Build 8863971619970531696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:14.848] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029401682045011120:62:0) after 1m0s
[01:35:58.596] Received PubSub notification, asking Buildbucket for the build status
[01:35:58.704] Build 8863971619970531696: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[01:35:58.704] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1605058550749495",
"created_by": "project:chromium",
"created_ts": "1605054946888503",
"id": "8863971619970531696",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6b58a991c2d6c0a2eb41f2b5937fe8abfe3def57\"}}",
"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\": \"6b58a991c2d6c0a2eb41f2b5937fe8abfe3def57\", \"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-1-83xn\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15580, \"clang_revision\": \"llvmorg-12-init-11060-g118c3f3c-1\", \"got_angle_revision\": \"98035e11d907c2dadd8d5aaa2309944c7a2d0bd3\", \"got_dawn_revision\": \"f3c829047220ec1305c6a3202ee3e067e10512c9\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"6b58a991c2d6c0a2eb41f2b5937fe8abfe3def57\", \"got_revision_cp\": \"refs/heads/master@{#826033}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"4ed9d3498dcffa987acba1a8007ff8dec336f263\", \"got_v8_revision\": \"2dbcdc105b963ee2501c82139eef7e0603977ff0\", \"got_v8_revision_cp\": \"refs/heads/8.8.278@{#1}\", \"got_webrtc_revision\": \"9c99b7964fabb16a022293ffbd2804f9b46551f6\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32565}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.win:win-asan\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6b58a991c2d6c0a2eb41f2b5937fe8abfe3def57\", \"swarm_hashes_refs/heads/master(at){#826033}_without_patch\": {\"absl_hardening_tests\": \"d8b3a17c46e911e9b2a4c4a722f7021e97df91b1\", \"accessibility_unittests\": \"6207b6e9ace807e1522df8707e3a4f9eee702924\", \"angle_unittests\": \"5e0d7df7ad16fe3573fde8f56e81434b687d0264\", \"app_shell_unittests\": \"19e2eb09f032544da0ad6a635bbc0c7695367aa5\", \"aura_unittests\": \"407afa03b91a6abecff902c441c00c91fc31a56d\", \"base_unittests\": \"e0df422645601a4193bfe68b4168abe6b0318538\", \"base_util_unittests\": \"33c048d68ebdc7d4e7f980a5f15b86e58077ae21\", \"blink_common_unittests\": \"2c26f530cf4b14944053bb60ffe8627f3cd3fa6f\", \"blink_fuzzer_unittests\": \"e963c765aa81eec9723536f94319abe6e37d76e1\", \"blink_heap_unittests\": \"6cb2600398e4fdf6da9e7de662024e6e9b450130\", \"blink_platform_unittests\": \"b6c26843616e9d0f2abed95f81e1767ee4d1949e\", \"blink_unittests\": \"12dff1d60c522dd2b5448e8a3d75b3bdb93716ca\", \"boringssl_crypto_tests\": \"23261c7397432237792d679bd249496d822ede99\", \"boringssl_ssl_tests\": \"7c460eb4241dc98eb505959165402a0bc002b061\", \"browser_switcher_bho_unittests\": \"f6a1bd58603cbf23f06b650c4cca5d67b4bae614\", \"browser_tests\": \"a92d60928cce1db0b5948b95d62d753fee45d5d8\", \"capture_unittests\": \"21744f4a51d810f7b8a718a989636e2a2c721a67\", \"cast_unittests\": \"c3a024641a74559c14e0e4fc68bf5dc0c3c25e01\", \"cc_unittests\": \"9f34eabfcfa24b66be11336805caa448870f3da4\", \"chrome_app_unittests\": \"c38beb5f35262eddd286a3d86412b3a9efbe28ad\", \"chrome_cleaner_unittests\": \"5eea145c40eaaca551593bd57290e0f40c12758c\", \"chrome_elf_unittests\": \"09dc371a864abb2c6d74154914787999d7215aa1\", \"chromedriver_unittests\": \"fb7c49b83e6aa630a29143f4a82db6e518e6fb3f\", \"components_browsertests\": \"041ae72314b7ee5372ce1de54d274ea6d27ae182\", \"components_unittests\": \"f11a3e1d55e81b27fa22f5096908c9c5e255f2be\", \"compositor_unittests\": \"0bf679a543656ec5dc567cb1cb91df2967704deb\", \"content_browsertests\": \"6e840796858afb8fd64cf2438fe778df66564fc5\", \"content_unittests\": \"b3a65bc96904c6884bbfc600571ebd5438f90169\", \"courgette_unittests\": \"429a9e88e24c98f6c628420e185e8628af4264d2\", \"crashpad_tests\": \"f710b343f95e6bbb59d94417ce814896c1af370d\", \"cronet_tests\": \"cb7df31b276374eab6eeb4adea0b17b80fbc180f\", \"cronet_unittests\": \"fba182ddc1fa94b1f22f7bfbf9507cd861bbe850\", \"crypto_unittests\": \"782961811b85db1ba55f5d0f60f1431c9c7c2cb8\", \"delayloads_unittests\": \"663379cfeb5da5581e4babbc50ea70c346850d1e\", \"device_unittests\": \"920837e8d6550f991e41db48c88b8c9a3a1d4592\", \"display_unittests\": \"43700bc6bf0d9fa856b30133cca37f557f80f8b8\", \"elevation_service_unittests\": \"9d566c8e44d7cd62ff2389973c4ad45907e9c094\", \"events_unittests\": \"dca3fd3679049b164ad5ca4423f3ebabae46d20f\", \"extensions_browsertests\": \"b306df143d95443fdd00a4698433e172f17b3e51\", \"extensions_unittests\": \"121d1a3735d5b3dbeda97042fd9b85a3ff295afa\", \"filesystem_service_unittests\": \"0efa7cacc665d4f2ce8df9aea63ade4fbcf3fd90\", \"gcm_unit_tests\": \"aecefe790ea8db84b35dc9b6bf41c5df57b430bb\", \"gcp_unittests\": \"f843466b400afecc35611cd9bbb251eb174f72e5\", \"gfx_unittests\": \"b073e0b9213519eb815e72beb20e2ad9b01eeed2\", \"gin_unittests\": \"8d08015ab99da29519c4127d9a6852e9b3a2b44e\", \"google_apis_unittests\": \"01be29d33c425f1ac4d48b87fdfc12de26bb3c17\", \"gpu_unittests\": \"6e997b1c45d58c2213f37d37e917b98994eb3337\", \"gwp_asan_unittests\": \"e3b53ca83df94d45a4bb25b3cba9b4491fac3600\", \"headless_browsertests\": \"13ae3eec223146896f31ac9416781a7a95bd6f28\", \"headless_unittests\": \"c568142205ef33b33b496bbd0fe20a86ff3bf212\", \"install_static_unittests\": \"d9e638fc81ec155468d6c8e6c7387d17d5449870\", \"installer_util_unittests\": \"faac0b3f25166fb2c75c53bf2d009f2f59c431dc\", \"interactive_ui_tests\": \"4af19862f089c3736dd74dc376bef1c498f4a0dc\", \"ipc_tests\": \"959a771bc7bb4affed1f6daad2dbe32146f5c981\", \"jingle_unittests\": \"56cb546787377b64129781289edbefdcbdba7947\", \"latency_unittests\": \"b32a36d9b8c5f8cadc4bba5fdbed2b4eb465d140\", \"libjingle_xmpp_unittests\": \"641a4c610236db771817b689b1cce779b48bd0ec\", \"liburlpattern_unittests\": \"b451aa09c334495a3671c81b9c07ff9150519664\", \"media_blink_unittests\": \"f8fa59f7d112cd6b23fbcbdc0843061483b002db\", \"media_unittests\": \"4b909b700c2f5d45a6f55a3a4a66799bd94bfb8d\", \"message_center_unittests\": \"f4619ce809990e3fdc331af4c8628d85386713a2\", \"midi_unittests\": \"ec3e6321a3fffba28ad3914cd00aef3b8bc20364\", \"mojo_core_unittests\": \"ac753a70ee1a1dd4ef98a137fe4b4398d07b4ab3\", \"mojo_unittests\": \"6c67ddac00a514a997cca1e5f424c5ff31da8586\", \"nacl_loader_unittests\": \"92505b573251b93844d7c117c88e0132f08c0774\", \"native_theme_unittests\": \"289ba48b09aa7b89dc5c403e83a699403df1ba2f\", \"net_unittests\": \"992bacb8d4a8b92f5e495795e89e3254d2cba2ca\", \"notification_helper_unittests\": \"3971d0e497927ed429a838f2a7094313c86de90a\", \"pdf_unittests\": \"6fa956c3f51394e4553963b55322a853233e786f\", \"perfetto_unittests\": \"214075d7c76539160e97100a65ef85735f11f76a\", \"ppapi_unittests\": \"ba7d183f86bbbc5c710deafd788bc43dde89d64f\", \"printing_unittests\": \"0a0f25fafcd7a7feec9ed71a954d1cf7f1c8b2e1\", \"remoting_unittests\": \"4cd643ece8dcca35ef709e9c6fe33841e0246672\", \"sbox_integration_tests\": \"553af3a414cf82b22aa858a6020bc754bfff80f0\", \"sbox_unittests\": \"c9a7e8bb7f6b53ebafa3cd3042027704e5d2e8c9\", \"sbox_validation_tests\": \"08cba287c50a59ca98fcc2271076312b4f17e657\", \"service_manager_unittests\": \"abef2850dec1a8207641f97a82eea845d14fa34f\", \"services_unittests\": \"ae04ffb28d57ad3e66a0a52bcda004a8f7329858\", \"setup_unittests\": \"af1c82cc76b85dd12d781f3487bcd176bfb7aa08\", \"shell_dialogs_unittests\": \"a93dafc138afe51c9b06a898d84e92e246cdd804\", \"skia_unittests\": \"0df703b0ca79da6f063548a824e1aec703660ff3\", \"snapshot_unittests\": \"c56620c7676f82a1d76f173a85ca43cbcb332fa8\", \"sql_unittests\": \"684b30e4f7b19382287d35b04c0749d15ec33f89\", \"storage_unittests\": \"9097318b604c41946d85b7b7013a54fcb6c4c943\", \"sync_integration_tests\": \"a2876f7118643031554c91b3ccc35a10a78bd02c\", \"traffic_annotation_auditor_unittests\": \"9ba60dc1d6690a67f4f9bc1dcf7d59a5ffd25984\", \"ui_base_unittests\": \"ac431b97f8a210d2c2cfa11561099a8e1041b6f9\", \"ui_touch_selection_unittests\": \"fb52069e2d700b6facc5e548a42a6dcff9e0d45d\", \"unit_tests\": \"cfae20cc99d32515c04b3b87a712e1e5b82ab336\", \"updater_tests\": \"66d81023a11343783abea13e55eefa79f147107f\", \"url_unittests\": \"25a48754c86b7e04a6a044d121aaa2a9f4cfbb6f\", \"views_unittests\": \"0facaa415a451ddc14982b07d6b0ddc27868b094\", \"viz_unittests\": \"54a233c977b65d9e08bf2cf354fb64102317fc9a\", \"vr_common_unittests\": \"5857f478439fa8bb92db3359986102dc1e00c4bb\", \"vr_pixeltests\": \"df873a5bad2a2950c49542fadaa4d07059b28d98\", \"weblayer_browsertests\": \"e7cd2340c70b6182e50df06e04d350c597afee7e\", \"weblayer_unittests\": \"a42470a0bfc6205f307e0246d745bfeda6e153af\", \"wm_unittests\": \"2c984b6c6c0db84a8cec32bb712847fe1ec96270\", \"wtf_unittests\": \"57d3d67e8adab0f06d3e51216117970f408bd13f\", \"zlib_unittests\": \"3fa319c4bc1d05197d4be85560424b2e9327f9de\", \"zucchini_unittests\": \"b02225abbc840e8f42c5fb4beb956440d4cbeaa6\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_91b2ef8341586e76bd64fa3093e74ce43aef344c2e2134fb3b046b47c3d4bcdb_v2\", \"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-1-83xn\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5626-39642e9\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1605054955744451",
"status": "COMPLETED",
"status_changed_ts": "1605058552553379",
"tags": [
"build_address:luci.chromium.ci/win-asan/15580",
"builder:win-asan",
"buildset:commit/git/6b58a991c2d6c0a2eb41f2b5937fe8abfe3def57",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6b58a991c2d6c0a2eb41f2b5937fe8abfe3def57",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029401682045011120",
"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/8863971619970531696/+/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:4fcd8c6f1c4f6a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1605058552751897",
"url": "https://ci.chromium.org/b/8863971619970531696",
"utcnow_ts": "1605058558693718"
}
}
[01:35:58.704] Invocation finished in 1h0m13.177491655s with status SUCCEEDED