[15:45:04.172] New invocation is queued and will start shortly
[15:45:05.242] Starting the invocation (attempt 1)
[15:45:05.279] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:45:05.279] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:45:05.279] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021553134071885584",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"36e78ce94df27027f886141a112a2b3b9825f4c5\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9021553134071885584",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/36e78ce94df27027f886141a112a2b3b9825f4c5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/36e78ce94df27027f886141a112a2b3b9825f4c5",
"gitiles_ref:refs/heads/master"
]
}
[15:45:06.013] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1612539905417674",
"id": "8856123072096043632",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"36e78ce94df27027f886141a112a2b3b9825f4c5\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612539905781511",
"tags": [
"build_address:luci.chromium.ci/win-asan/17182",
"builder:win-asan",
"buildset:commit/git/36e78ce94df27027f886141a112a2b3b9825f4c5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/36e78ce94df27027f886141a112a2b3b9825f4c5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021553134071885584",
"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/8856123072096043632/+/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": "1612539905781627",
"url": "https://ci.chromium.org/b/8856123072096043632",
"utcnow_ts": "1612539906005567"
}
}
[15:45:06.013] Task URL: https://ci.chromium.org/b/8856123072096043632
[15:45:06.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:2:0) after 1m0s
[15:46:06.038] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:2:0)
[15:46:06.038] Timer tick, asking Buildbucket for the build status
[15:46:06.168] Build 8856123072096043632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:46:06.169] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:3:0) after 1m0s
[15:47:06.186] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:3:0)
[15:47:06.186] Timer tick, asking Buildbucket for the build status
[15:47:06.474] Build 8856123072096043632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:47:06.474] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:4:0) after 1m0s
[15:48:06.757] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:4:0)
[15:48:06.757] Timer tick, asking Buildbucket for the build status
[15:48:06.850] Build 8856123072096043632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:48:06.850] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:5:0) after 1m0s
[15:49:06.844] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:5:0)
[15:49:06.844] Timer tick, asking Buildbucket for the build status
[15:49:06.924] Build 8856123072096043632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:49:06.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:6:0) after 1m0s
[15:50:06.914] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:6:0)
[15:50:06.914] Timer tick, asking Buildbucket for the build status
[15:50:07.056] Build 8856123072096043632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:50:07.056] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:7:0) after 1m0s
[15:51:01.740] Received PubSub notification, asking Buildbucket for the build status
[15:51:01.885] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:07.045] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:7:0)
[15:51:07.045] Timer tick, asking Buildbucket for the build status
[15:51:07.286] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:07.286] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:9:0) after 1m0s
[15:52:07.314] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:9:0)
[15:52:07.314] Timer tick, asking Buildbucket for the build status
[15:52:07.426] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:07.426] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:10:0) after 1m0s
[15:53:07.489] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:10:0)
[15:53:07.489] Timer tick, asking Buildbucket for the build status
[15:53:07.647] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:07.647] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:11:0) after 1m0s
[15:54:07.666] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:11:0)
[15:54:07.666] Timer tick, asking Buildbucket for the build status
[15:54:07.778] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:07.778] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:12:0) after 1m0s
[15:55:07.786] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:12:0)
[15:55:07.786] Timer tick, asking Buildbucket for the build status
[15:55:07.921] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:07.921] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:13:0) after 1m0s
[15:56:07.925] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:13:0)
[15:56:07.925] Timer tick, asking Buildbucket for the build status
[15:56:08.102] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:08.102] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:14:0) after 1m0s
[15:57:08.121] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:14:0)
[15:57:08.121] Timer tick, asking Buildbucket for the build status
[15:57:08.177] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:08.177] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:15:0) after 1m0s
[15:58:08.258] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:15:0)
[15:58:08.258] Timer tick, asking Buildbucket for the build status
[15:58:08.830] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:08.830] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:16:0) after 1m0s
[15:59:08.878] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:16:0)
[15:59:08.878] Timer tick, asking Buildbucket for the build status
[15:59:08.944] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:08.944] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:17:0) after 1m0s
[16:00:08.969] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:17:0)
[16:00:08.969] Timer tick, asking Buildbucket for the build status
[16:00:09.141] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:09.141] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:18:0) after 1m0s
[16:01:09.245] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:18:0)
[16:01:09.245] Timer tick, asking Buildbucket for the build status
[16:01:09.309] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:09.309] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:19:0) after 1m0s
[16:02:09.387] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:19:0)
[16:02:09.387] Timer tick, asking Buildbucket for the build status
[16:02:09.687] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:09.687] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:20:0) after 1m0s
[16:03:09.734] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:20:0)
[16:03:09.734] Timer tick, asking Buildbucket for the build status
[16:03:09.793] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:09.793] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:21:0) after 1m0s
[16:04:09.858] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:21:0)
[16:04:09.858] Timer tick, asking Buildbucket for the build status
[16:04:09.927] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:09.927] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:22:0) after 1m0s
[16:05:09.996] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:22:0)
[16:05:09.996] Timer tick, asking Buildbucket for the build status
[16:05:10.218] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:10.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:23:0) after 1m0s
[16:06:10.358] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:23:0)
[16:06:10.358] Timer tick, asking Buildbucket for the build status
[16:06:10.501] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:10.501] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:24:0) after 1m0s
[16:07:10.531] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:24:0)
[16:07:10.532] Timer tick, asking Buildbucket for the build status
[16:07:10.614] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:10.614] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:25:0) after 1m0s
[16:08:10.659] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:25:0)
[16:08:10.659] Timer tick, asking Buildbucket for the build status
[16:08:10.783] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:10.783] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:26:0) after 1m0s
[16:09:10.806] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:26:0)
[16:09:10.806] Timer tick, asking Buildbucket for the build status
[16:09:10.933] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:10.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:27:0) after 1m0s
[16:10:10.921] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:27:0)
[16:10:10.921] Timer tick, asking Buildbucket for the build status
[16:10:11.083] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:11.083] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:28:0) after 1m0s
[16:11:11.125] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:28:0)
[16:11:11.125] Timer tick, asking Buildbucket for the build status
[16:11:11.501] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:11.501] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:29:0) after 1m0s
[16:12:11.561] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:29:0)
[16:12:11.561] Timer tick, asking Buildbucket for the build status
[16:12:12.153] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:12.153] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:30:0) after 1m0s
[16:13:12.312] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:30:0)
[16:13:12.312] Timer tick, asking Buildbucket for the build status
[16:13:12.424] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:12.424] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:31:0) after 1m0s
[16:14:12.565] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:31:0)
[16:14:12.565] Timer tick, asking Buildbucket for the build status
[16:14:13.092] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:13.092] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:32:0) after 1m0s
[16:15:13.192] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:32:0)
[16:15:13.192] Timer tick, asking Buildbucket for the build status
[16:15:13.549] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:13.549] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:33:0) after 1m0s
[16:16:13.566] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:33:0)
[16:16:13.566] Timer tick, asking Buildbucket for the build status
[16:16:13.758] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:13.758] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:34:0) after 1m0s
[16:17:13.742] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:34:0)
[16:17:13.742] Timer tick, asking Buildbucket for the build status
[16:17:13.849] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:13.849] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:35:0) after 1m0s
[16:18:13.872] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:35:0)
[16:18:13.872] Timer tick, asking Buildbucket for the build status
[16:18:13.986] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:13.986] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:36:0) after 1m0s
[16:19:14.019] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:36:0)
[16:19:14.019] Timer tick, asking Buildbucket for the build status
[16:19:14.214] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:14.214] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:37:0) after 1m0s
[16:20:14.237] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:37:0)
[16:20:14.237] Timer tick, asking Buildbucket for the build status
[16:20:14.357] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:14.358] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:38:0) after 1m0s
[16:21:14.443] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:38:0)
[16:21:14.443] Timer tick, asking Buildbucket for the build status
[16:21:14.505] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:14.505] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:39:0) after 1m0s
[16:22:14.553] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:39:0)
[16:22:14.553] Timer tick, asking Buildbucket for the build status
[16:22:15.020] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:15.020] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:40:0) after 1m0s
[16:23:15.137] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:40:0)
[16:23:15.137] Timer tick, asking Buildbucket for the build status
[16:23:15.249] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:15.249] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:41:0) after 1m0s
[16:24:15.288] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:41:0)
[16:24:15.288] Timer tick, asking Buildbucket for the build status
[16:24:15.901] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:15.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:42:0) after 1m0s
[16:25:15.956] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:42:0)
[16:25:15.956] Timer tick, asking Buildbucket for the build status
[16:25:16.096] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:16.096] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:43:0) after 1m0s
[16:26:16.165] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:43:0)
[16:26:16.165] Timer tick, asking Buildbucket for the build status
[16:26:16.438] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:16.438] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:44:0) after 1m0s
[16:27:16.462] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:44:0)
[16:27:16.462] Timer tick, asking Buildbucket for the build status
[16:27:16.702] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:16.702] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:45:0) after 1m0s
[16:28:16.722] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:45:0)
[16:28:16.722] Timer tick, asking Buildbucket for the build status
[16:28:16.824] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:16.824] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:46:0) after 1m0s
[16:29:16.850] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:46:0)
[16:29:16.850] Timer tick, asking Buildbucket for the build status
[16:29:16.928] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:16.928] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:47:0) after 1m0s
[16:30:16.971] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:47:0)
[16:30:16.971] Timer tick, asking Buildbucket for the build status
[16:30:17.172] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:17.172] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:48:0) after 1m0s
[16:31:17.276] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:48:0)
[16:31:17.276] Timer tick, asking Buildbucket for the build status
[16:31:17.354] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:17.355] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:49:0) after 1m0s
[16:32:17.679] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:49:0)
[16:32:17.679] Timer tick, asking Buildbucket for the build status
[16:32:17.999] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:17.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:50:0) after 1m0s
[16:33:18.022] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:50:0)
[16:33:18.022] Timer tick, asking Buildbucket for the build status
[16:33:18.234] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:18.234] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:51:0) after 1m0s
[16:34:18.257] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:51:0)
[16:34:18.257] Timer tick, asking Buildbucket for the build status
[16:34:18.362] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:18.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:52:0) after 1m0s
[16:35:18.395] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:52:0)
[16:35:18.395] Timer tick, asking Buildbucket for the build status
[16:35:18.667] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:18.667] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:53:0) after 1m0s
[16:36:18.727] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:53:0)
[16:36:18.727] Timer tick, asking Buildbucket for the build status
[16:36:19.992] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:19.992] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:54:0) after 1m0s
[16:37:20.116] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:54:0)
[16:37:20.116] Timer tick, asking Buildbucket for the build status
[16:37:20.505] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:20.505] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:55:0) after 1m0s
[16:38:20.524] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:55:0)
[16:38:20.524] Timer tick, asking Buildbucket for the build status
[16:38:20.804] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:20.804] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:56:0) after 1m0s
[16:39:20.824] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:56:0)
[16:39:20.824] Timer tick, asking Buildbucket for the build status
[16:39:21.105] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:21.105] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:57:0) after 1m0s
[16:40:21.171] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:57:0)
[16:40:21.171] Timer tick, asking Buildbucket for the build status
[16:40:21.420] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:21.420] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:58:0) after 1m0s
[16:41:21.439] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:58:0)
[16:41:21.439] Timer tick, asking Buildbucket for the build status
[16:41:21.602] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:21.602] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:59:0) after 1m0s
[16:42:22.913] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:59:0)
[16:42:22.913] Timer tick, asking Buildbucket for the build status
[16:42:23.611] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:23.611] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:60:0) after 1m0s
[16:43:24.101] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:60:0)
[16:43:24.101] Timer tick, asking Buildbucket for the build status
[16:43:25.044] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:25.044] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:61:0) after 1m0s
[16:44:25.158] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:61:0)
[16:44:25.158] Timer tick, asking Buildbucket for the build status
[16:44:25.612] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:25.612] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:62:0) after 1m0s
[16:45:25.827] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:62:0)
[16:45:25.827] Timer tick, asking Buildbucket for the build status
[16:45:26.496] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:26.496] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:63:0) after 1m0s
[16:46:26.636] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:63:0)
[16:46:26.636] Timer tick, asking Buildbucket for the build status
[16:46:26.701] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:26.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:64:0) after 1m0s
[16:47:26.791] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:64:0)
[16:47:26.791] Timer tick, asking Buildbucket for the build status
[16:47:27.338] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:27.338] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:65:0) after 1m0s
[16:48:27.352] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:65:0)
[16:48:27.352] Timer tick, asking Buildbucket for the build status
[16:48:27.963] Build 8856123072096043632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:27.964] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021553134071885584:66:0) after 1m0s
[16:49:27.784] Received PubSub notification, asking Buildbucket for the build status
[16:49:27.857] Build 8856123072096043632: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:49:27.857] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1612543764058816",
"created_by": "project:chromium",
"created_ts": "1612539905417674",
"id": "8856123072096043632",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"36e78ce94df27027f886141a112a2b3b9825f4c5\"}}",
"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\": \"36e78ce94df27027f886141a112a2b3b9825f4c5\", \"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-be5b\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17182, \"clang_revision\": \"llvmorg-13-init-794-g83e2710e-1\", \"got_angle_revision\": \"82df803412eced8a2ec7e4ddc63570f64b7f35ac\", \"got_dawn_revision\": \"185c6a5b0f358c115e3d3ef43afbb157d4c4b591\", \"got_nacl_revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"got_revision\": \"36e78ce94df27027f886141a112a2b3b9825f4c5\", \"got_revision_cp\": \"refs/heads/master@{#851131}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"14dcbed947ce755af1fcbfb3ba8e643b4e769860\", \"got_v8_revision\": \"7a64550888c94b882033f89163526edc7ff4f863\", \"got_v8_revision_cp\": \"refs/heads/9.0.135@{#1}\", \"got_webrtc_revision\": \"1d71fd9c61db8f1dcf30b5d0625e514b91200926\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33155}\", \"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\": \"36e78ce94df27027f886141a112a2b3b9825f4c5\", \"swarm_hashes_refs/heads/master(at){#851131}_without_patch\": {\"absl_hardening_tests\": \"aad753e467464e4cbb4167d5ddbd33af9a596ffa\", \"accessibility_unittests\": \"53f6bf1264f31e9cb92bf4405fe2260dfd6e8791\", \"angle_unittests\": \"51a33e7211bf8acc6bbe4084bf365b8d0fb0d9ab\", \"app_shell_unittests\": \"a0b373a45360c60e8916a350fa79f1e3a7e8a48f\", \"aura_unittests\": \"6ff22b494ed493da8fe24df9729c6676fac168a4\", \"base_unittests\": \"ec183eae61cfa2fde8ca0d19c169b5e294e658d3\", \"base_util_unittests\": \"be45a71b4475740afbaf157b84cd06177b7fcc56\", \"blink_common_unittests\": \"f19e4316918daa6be1e8d203c3d3739a1e48866c\", \"blink_fuzzer_unittests\": \"9065d9ff59fa30eaa0e7daad919d81d74970632c\", \"blink_heap_unittests\": \"ff12fc1bb136ec4a75fbe076a9ff0d501b118081\", \"blink_platform_unittests\": \"03155cef8f824990f74848e69472908bf5b9ca68\", \"blink_unittests\": \"eaaa0df304b36ddb0869b42cd3aa3cca42517bdd\", \"boringssl_crypto_tests\": \"bdd426956f5a015fe891f1400ed1ce85e41a4bea\", \"boringssl_ssl_tests\": \"2d233be7065ce1289d8e61574153ec66681bf93a\", \"browser_switcher_bho_unittests\": \"0fc9d8fa3c47ebf0dc889b68c9403b1411f528d2\", \"browser_tests\": \"de1f99ef7e333a236dbb0f2d80e2fbd30f6cf742\", \"capture_unittests\": \"ae2c6850eb574201b3db517e03e2610a6074c2f4\", \"cast_unittests\": \"803b9f3886b106712d0d3d12ceb119d721577189\", \"cc_unittests\": \"2fd63b55b33e2c418a1938acc99999ee7d5a49db\", \"chrome_app_unittests\": \"72d99432206fb1cf0177a91b353844e079b988a5\", \"chrome_cleaner_unittests\": \"b49fb18a314b05d3935cae0b8c51b303987934d6\", \"chrome_elf_unittests\": \"37e128f2f8532b27c8aa3e6b411759c152fc2b9b\", \"chromedriver_unittests\": \"19ef71f61032222aa4cc289f16770c873325dae3\", \"components_browsertests\": \"db152aaabd80f6d31b5b93fdb10b6880aa4fe432\", \"components_unittests\": \"f5311d75196d37574b852d9c542787bed7c99061\", \"compositor_unittests\": \"a92fb7c81464a1e29ed1beb3cb1023f653144908\", \"content_browsertests\": \"2ea4861d924626c2b88c38ab8d813c24daa72407\", \"content_unittests\": \"9035fb2acbb13d72f1cbe2c3bea900c8c175f1e8\", \"courgette_unittests\": \"fdd75350931f2f1276a02e94fd3f286a650e6d98\", \"crashpad_tests\": \"c70926bcd5a42c72e42b4ce652d2389212790344\", \"cronet_tests\": \"eca15dcf0015effe25c9d9637b31dc9fd55139eb\", \"cronet_unittests\": \"105d22c66df352394698af0d23281a691c2e9c6f\", \"crypto_unittests\": \"04ec92b616a3f0e9a31a9725e3a08dd6223cb10f\", \"delayloads_unittests\": \"ca337b63839ce57a5e1ab2713acf301f225b177b\", \"device_unittests\": \"ec05e24dde05cae05452fa05ce951d314ed4cb02\", \"display_unittests\": \"47bc6d0b4a8164b0b95eebd2569827a16c1ab779\", \"elevation_service_unittests\": \"a16524d320a8bfeb6ccc3d979010795fb6f87afc\", \"events_unittests\": \"ecc056c9f5f352a59a67cc7cdd064acdbdce5b57\", \"extensions_browsertests\": \"9efeadb0588082446a2a10551c01dcf6b63bac7c\", \"extensions_unittests\": \"edae71bcbd942394b994db598b0d31908cf7c17e\", \"filesystem_service_unittests\": \"caa2cbd62292e39b761c113216fb881258bdaf7c\", \"gcm_unit_tests\": \"e937159a51fc088539064f73afe637e653d5ea82\", \"gcp_unittests\": \"183124f850be1009767c48efa1b1e17ffee56636\", \"gfx_unittests\": \"36c1aab8cfbb678296da38d130b2cf65e67e4a1b\", \"gin_unittests\": \"a3aee4f7b608216e8f4731139a3dc9a03275a6ee\", \"google_apis_unittests\": \"6ba8b31751adbd947dd4b86e2c78210a2b04ea33\", \"gpu_unittests\": \"40d49e0f34dee994942ef2114c13075b0d2d45a9\", \"gwp_asan_unittests\": \"eaf0242c811eafb5b67835314e5afa13ec5afe52\", \"headless_browsertests\": \"22df54a99eaf45e4c424ea774f53ce9279eaaa50\", \"headless_unittests\": \"cdb17f37aeb4fb694f6d319189c1045c64e0c7bb\", \"install_static_unittests\": \"fdd4fda4d69ade1abfcb0ca9d28d7530d21e52f3\", \"installer_util_unittests\": \"1f3018e5bee1210a5c27d8c68d1a8bdf7e3ad829\", \"interactive_ui_tests\": \"f1b3aec8bd4a442c1d0d2180ccbbfbb4816efdb4\", \"ipc_tests\": \"c252c4c1d46426dd22c7548fabdf3fd4815ddbda\", \"jingle_unittests\": \"f26e004480486e86a303b96b890ba48bedbe51f0\", \"latency_unittests\": \"188e93f5b44a8797492e182846d70b68d7560e63\", \"libjingle_xmpp_unittests\": \"828832b19e6caf1c3da005d9bf0e74ee01ad30e5\", \"liburlpattern_unittests\": \"a88e6ed4555c7ae40da9dc8c3e222b46dcb3ea4a\", \"media_blink_unittests\": \"fdebe7817d93334b60e72764e4c6a12d4b25425d\", \"media_unittests\": \"f7979a5a28e39ba0e096f792e6a8f21e9325af84\", \"message_center_unittests\": \"f978103c316b7293b238bd350f94942613901508\", \"midi_unittests\": \"febf39f4db2541f87b41d2679db86105f8c39d60\", \"mojo_core_unittests\": \"51884abcca801d2248eb7d3a047f9773e1410020\", \"mojo_unittests\": \"bf66140091251b8772b4c05d5d8df2f97bc70f6a\", \"nacl_loader_unittests\": \"8f1986885771c9b99ed52899210494253b320aa4\", \"native_theme_unittests\": \"534f498d1d3cd8def566268aee151a00652e9c38\", \"net_unittests\": \"65b007d4f8548547e2cc7931863a9c233db20dc1\", \"notification_helper_unittests\": \"8501d43186f1d29ab253292f06fb7fa2bddca5c4\", \"pdf_unittests\": \"0c79673d0fc84698521473999d5942566b1ddbef\", \"perfetto_unittests\": \"06c3fbeb4e5d8f2b4bf6c80880a9b400c0080166\", \"ppapi_unittests\": \"42dbabe50e72d8d2b7908930e8ce69501cb5619c\", \"printing_unittests\": \"cd98577ce17e4b09dbbef4fe1dc0ad209ebebfeb\", \"remoting_unittests\": \"fccbe5537db0c227b46a214895e52bd252540bf8\", \"sbox_integration_tests\": \"b0bb3278f2272757af1ece83dfddf30773b9a8cf\", \"sbox_unittests\": \"f1f1183c523473235d0c5da3f8188c9c08fac18b\", \"sbox_validation_tests\": \"201f4a10b1b20806fb991800f797aa9ef591d13d\", \"service_manager_unittests\": \"23f799497cf879f0fb38589e38eb8f10a2517792\", \"services_unittests\": \"3a45a778e8669ee411e0da38c69561ac584e614c\", \"setup_unittests\": \"cf4bcde187b593466f47232a2a0f98085c93a446\", \"shell_dialogs_unittests\": \"2ac12a84d0ddff616bdbd7b56ae19de2b1ec5d4a\", \"skia_unittests\": \"f8e751711ac9b615d2ac3bc3ea56a20acbfba4bd\", \"snapshot_unittests\": \"eabf98e66826a76f45173b6ad85aa01d4e22b827\", \"sql_unittests\": \"d884bda823ebcc99aa5acaf790ce41a08d3db23d\", \"storage_unittests\": \"5b9609adba9456a99baf2ba9b7e5ffa3c75e0a50\", \"sync_integration_tests\": \"3ac199ceb7a899093227a38ac65c4d767d399f43\", \"traffic_annotation_auditor_unittests\": \"d19d7ddb62815eba0f74e34ad15681e321266ee5\", \"ui_base_unittests\": \"c523645c5c4ecaad57641046434fa549d9d6ebb6\", \"ui_touch_selection_unittests\": \"9b670c09d576d01f20fc1365b80cace05802a9bd\", \"unit_tests\": \"e71d0f120a1dd20cf899add9cdf34cd2ac5e5964\", \"updater_tests\": \"fd20c425375f832b0f8c1c25ea7c29930410ebfd\", \"url_unittests\": \"3a75e2c3b2dd6b5b6e6e466dbc9791200148d5f0\", \"views_unittests\": \"095e07c8349a7f20e08410f22578283e1c471a2b\", \"viz_unittests\": \"7bce9decef99922b511fd01dc325473c6b4b2948\", \"vr_common_unittests\": \"8aaa14a1742cb75b0d464c62053ae08aa38fbc8e\", \"vr_pixeltests\": \"5a00c78db73eaa393b6e7ee73819de2ccd2c9ab8\", \"weblayer_browsertests\": \"e781feb2c3f438fb06834dcdbf40660701b84378\", \"weblayer_unittests\": \"ef96e53e62c9e697e00165ac9f86555a1338965e\", \"wm_unittests\": \"67f038c494c9abc22598efb813daf2f4464a19ee\", \"wtf_unittests\": \"a433c19aa1e5332baeb5f7c1836eeac674ffafb4\", \"zlib_unittests\": \"242ad0aa555691551c39db1126490d32dd206b37\", \"zucchini_unittests\": \"1606c349feea14415d9593a8b780447577ef01f2\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_02613bacee8cd1c41158238da4ce7615021ecb28a4b52e6029f89cb601af0790_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-0-be5b\"], \"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\": [\"5676-c76598c\"], \"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": "1612540261032323",
"status": "COMPLETED",
"status_changed_ts": "1612543764632110",
"tags": [
"build_address:luci.chromium.ci/win-asan/17182",
"builder:win-asan",
"buildset:commit/git/36e78ce94df27027f886141a112a2b3b9825f4c5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/36e78ce94df27027f886141a112a2b3b9825f4c5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021553134071885584",
"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/8856123072096043632/+/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:518bafd02ddac910",
"user_agent:luci-scheduler"
],
"updated_ts": "1612543764641935",
"url": "https://ci.chromium.org/b/8856123072096043632",
"utcnow_ts": "1612543767849103"
}
}
[16:49:27.857] Invocation finished in 1h4m23.708424947s with status SUCCEEDED