[13:38:25.560] New invocation is queued and will start shortly
[13:38:26.707] Starting the invocation (attempt 1)
[13:38:26.762] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:38:26.763] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:38:26.763] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023010653250308096",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"573dad086b11e6b4c7c7a9ad36d5079658650a4b\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9023010653250308096",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/573dad086b11e6b4c7c7a9ad36d5079658650a4b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/573dad086b11e6b4c7c7a9ad36d5079658650a4b",
"gitiles_ref:refs/heads/master"
]
}
[13:38:27.497] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611149906864031",
"id": "8857580591217951184",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"573dad086b11e6b4c7c7a9ad36d5079658650a4b\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611149907255631",
"tags": [
"build_address:luci.chromium.ci/win-asan/16911",
"builder:win-asan",
"buildset:commit/git/573dad086b11e6b4c7c7a9ad36d5079658650a4b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/573dad086b11e6b4c7c7a9ad36d5079658650a4b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023010653250308096",
"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/8857580591217951184/+/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": "1611149907255748",
"url": "https://ci.chromium.org/b/8857580591217951184",
"utcnow_ts": "1611149907487829"
}
}
[13:38:27.497] Task URL: https://ci.chromium.org/b/8857580591217951184
[13:38:27.497] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:2:0) after 1m0s
[13:38:30.072] Received PubSub notification, asking Buildbucket for the build status
[13:38:30.186] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:27.604] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:2:0)
[13:39:27.604] Timer tick, asking Buildbucket for the build status
[13:39:28.130] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:28.131] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:4:0) after 1m0s
[13:40:28.180] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:4:0)
[13:40:28.180] Timer tick, asking Buildbucket for the build status
[13:40:28.319] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:28.319] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:5:0) after 1m0s
[13:41:28.353] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:5:0)
[13:41:28.353] Timer tick, asking Buildbucket for the build status
[13:41:28.890] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:28.890] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:6:0) after 1m0s
[13:42:29.139] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:6:0)
[13:42:29.139] Timer tick, asking Buildbucket for the build status
[13:42:29.245] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:29.245] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:7:0) after 1m0s
[13:43:29.398] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:7:0)
[13:43:29.398] Timer tick, asking Buildbucket for the build status
[13:43:29.522] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:29.522] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:8:0) after 1m0s
[13:44:29.549] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:8:0)
[13:44:29.549] Timer tick, asking Buildbucket for the build status
[13:44:29.810] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:29.810] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:9:0) after 1m0s
[13:45:29.878] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:9:0)
[13:45:29.878] Timer tick, asking Buildbucket for the build status
[13:45:29.936] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:29.936] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:10:0) after 1m0s
[13:46:29.952] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:10:0)
[13:46:29.952] Timer tick, asking Buildbucket for the build status
[13:46:30.061] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:30.061] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:11:0) after 1m0s
[13:47:30.107] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:11:0)
[13:47:30.107] Timer tick, asking Buildbucket for the build status
[13:47:30.229] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:30.229] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:12:0) after 1m0s
[13:48:30.249] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:12:0)
[13:48:30.249] Timer tick, asking Buildbucket for the build status
[13:48:30.436] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:30.436] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:13:0) after 1m0s
[13:49:30.551] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:13:0)
[13:49:30.551] Timer tick, asking Buildbucket for the build status
[13:49:30.785] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:30.785] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:14:0) after 1m0s
[13:50:30.789] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:14:0)
[13:50:30.789] Timer tick, asking Buildbucket for the build status
[13:50:30.872] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:30.872] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:15:0) after 1m0s
[13:51:30.911] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:15:0)
[13:51:30.911] Timer tick, asking Buildbucket for the build status
[13:51:31.021] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:31.021] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:16:0) after 1m0s
[13:52:31.076] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:16:0)
[13:52:31.076] Timer tick, asking Buildbucket for the build status
[13:52:31.184] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:31.184] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:17:0) after 1m0s
[13:53:31.237] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:17:0)
[13:53:31.237] Timer tick, asking Buildbucket for the build status
[13:53:31.458] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:31.458] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:18:0) after 1m0s
[13:54:31.573] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:18:0)
[13:54:31.573] Timer tick, asking Buildbucket for the build status
[13:54:31.727] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:31.727] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:19:0) after 1m0s
[13:55:31.814] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:19:0)
[13:55:31.814] Timer tick, asking Buildbucket for the build status
[13:55:32.153] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:32.153] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:20:0) after 1m0s
[13:56:32.174] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:20:0)
[13:56:32.174] Timer tick, asking Buildbucket for the build status
[13:56:32.334] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:32.334] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:21:0) after 1m0s
[13:57:33.590] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:21:0)
[13:57:33.590] Timer tick, asking Buildbucket for the build status
[13:57:33.696] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:33.696] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:22:0) after 1m0s
[13:58:33.843] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:22:0)
[13:58:33.843] Timer tick, asking Buildbucket for the build status
[13:58:33.963] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:33.963] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:23:0) after 1m0s
[13:59:34.038] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:23:0)
[13:59:34.038] Timer tick, asking Buildbucket for the build status
[13:59:34.178] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:34.178] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:24:0) after 1m0s
[14:00:36.163] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:24:0)
[14:00:36.163] Timer tick, asking Buildbucket for the build status
[14:00:36.311] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:36.311] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:25:0) after 1m0s
[14:01:36.421] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:25:0)
[14:01:36.422] Timer tick, asking Buildbucket for the build status
[14:01:36.485] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:36.485] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:26:0) after 1m0s
[14:02:36.581] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:26:0)
[14:02:36.581] Timer tick, asking Buildbucket for the build status
[14:02:36.663] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:36.664] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:27:0) after 1m0s
[14:03:36.721] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:27:0)
[14:03:36.721] Timer tick, asking Buildbucket for the build status
[14:03:36.874] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:36.874] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:28:0) after 1m0s
[14:04:36.910] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:28:0)
[14:04:36.910] Timer tick, asking Buildbucket for the build status
[14:04:36.972] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:36.972] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:29:0) after 1m0s
[14:05:36.990] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:29:0)
[14:05:36.990] Timer tick, asking Buildbucket for the build status
[14:05:37.122] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:37.122] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:30:0) after 1m0s
[14:06:37.176] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:30:0)
[14:06:37.176] Timer tick, asking Buildbucket for the build status
[14:06:37.309] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:37.309] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:31:0) after 1m0s
[14:07:37.354] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:31:0)
[14:07:37.354] Timer tick, asking Buildbucket for the build status
[14:07:37.580] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:37.580] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:32:0) after 1m0s
[14:08:37.612] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:32:0)
[14:08:37.612] Timer tick, asking Buildbucket for the build status
[14:08:38.178] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:38.178] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:33:0) after 1m0s
[14:09:38.198] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:33:0)
[14:09:38.198] Timer tick, asking Buildbucket for the build status
[14:09:38.272] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:38.272] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:34:0) after 1m0s
[14:10:38.323] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:34:0)
[14:10:38.323] Timer tick, asking Buildbucket for the build status
[14:10:38.811] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:38.811] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:35:0) after 1m0s
[14:11:38.836] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:35:0)
[14:11:38.836] Timer tick, asking Buildbucket for the build status
[14:11:39.416] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:39.416] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:36:0) after 1m0s
[14:12:39.451] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:36:0)
[14:12:39.451] Timer tick, asking Buildbucket for the build status
[14:12:39.583] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:39.583] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:37:0) after 1m0s
[14:13:39.679] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:37:0)
[14:13:39.680] Timer tick, asking Buildbucket for the build status
[14:13:40.015] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:40.015] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:38:0) after 1m0s
[14:14:40.107] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:38:0)
[14:14:40.107] Timer tick, asking Buildbucket for the build status
[14:14:40.193] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:40.193] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:39:0) after 1m0s
[14:15:40.192] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:39:0)
[14:15:40.192] Timer tick, asking Buildbucket for the build status
[14:15:40.594] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:40.594] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:40:0) after 1m0s
[14:16:40.678] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:40:0)
[14:16:40.678] Timer tick, asking Buildbucket for the build status
[14:16:40.890] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:40.891] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:41:0) after 1m0s
[14:17:40.929] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:41:0)
[14:17:40.929] Timer tick, asking Buildbucket for the build status
[14:17:41.037] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:41.037] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:42:0) after 1m0s
[14:18:41.140] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:42:0)
[14:18:41.140] Timer tick, asking Buildbucket for the build status
[14:18:41.262] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:41.262] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:43:0) after 1m0s
[14:19:41.248] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:43:0)
[14:19:41.248] Timer tick, asking Buildbucket for the build status
[14:19:41.523] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:41.523] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:44:0) after 1m0s
[14:20:41.611] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:44:0)
[14:20:41.611] Timer tick, asking Buildbucket for the build status
[14:20:41.692] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:41.692] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:45:0) after 1m0s
[14:21:41.844] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:45:0)
[14:21:41.844] Timer tick, asking Buildbucket for the build status
[14:21:42.164] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:42.164] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:46:0) after 1m0s
[14:22:42.285] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:46:0)
[14:22:42.285] Timer tick, asking Buildbucket for the build status
[14:22:42.894] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:42.894] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:47:0) after 1m0s
[14:23:42.953] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:47:0)
[14:23:42.953] Timer tick, asking Buildbucket for the build status
[14:23:43.229] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:43.230] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:48:0) after 1m0s
[14:24:43.299] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:48:0)
[14:24:43.299] Timer tick, asking Buildbucket for the build status
[14:24:43.742] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:43.742] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:49:0) after 1m0s
[14:25:43.862] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:49:0)
[14:25:43.862] Timer tick, asking Buildbucket for the build status
[14:25:43.969] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:43.969] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:50:0) after 1m0s
[14:26:44.032] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:50:0)
[14:26:44.032] Timer tick, asking Buildbucket for the build status
[14:26:44.443] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:44.443] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:51:0) after 1m0s
[14:27:44.531] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:51:0)
[14:27:44.531] Timer tick, asking Buildbucket for the build status
[14:27:44.607] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:44.607] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:52:0) after 1m0s
[14:28:44.622] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:52:0)
[14:28:44.622] Timer tick, asking Buildbucket for the build status
[14:28:44.766] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:44.766] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:53:0) after 1m0s
[14:29:44.876] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:53:0)
[14:29:44.876] Timer tick, asking Buildbucket for the build status
[14:29:45.337] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:45.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:54:0) after 1m0s
[14:30:45.388] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:54:0)
[14:30:45.388] Timer tick, asking Buildbucket for the build status
[14:30:45.678] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:45.678] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:55:0) after 1m0s
[14:31:45.680] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:55:0)
[14:31:45.680] Timer tick, asking Buildbucket for the build status
[14:31:45.753] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:45.753] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:56:0) after 1m0s
[14:32:45.800] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:56:0)
[14:32:45.800] Timer tick, asking Buildbucket for the build status
[14:32:45.919] Build 8857580591217951184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:45.919] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023010653250308096:57:0) after 1m0s
[14:33:21.342] Received PubSub notification, asking Buildbucket for the build status
[14:33:21.443] Build 8857580591217951184: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[14:33:21.443] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611153200058453",
"created_by": "project:chromium",
"created_ts": "1611149906864031",
"id": "8857580591217951184",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"573dad086b11e6b4c7c7a9ad36d5079658650a4b\"}}",
"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\": \"573dad086b11e6b4c7c7a9ad36d5079658650a4b\", \"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-us-central1-b-32-0-gbsn\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16911, \"clang_revision\": \"llvmorg-12-init-16548-gf1d5cbbd-1\", \"got_angle_revision\": \"186fe99034047745c4a626935b3ac2cce557f8f3\", \"got_dawn_revision\": \"4b316994ad01ab6b87032c5fd9b53c6d18956d8d\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"573dad086b11e6b4c7c7a9ad36d5079658650a4b\", \"got_revision_cp\": \"refs/heads/master@{#845135}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"22be66d4071284ffeeef424adc068e9e400835ab\", \"got_v8_revision\": \"0c46f3e0ded50952ed6f46254bbd16776f532aa0\", \"got_v8_revision_cp\": \"refs/heads/9.0.20@{#1}\", \"got_webrtc_revision\": \"6dcbcead3da45604b84aef9d9496689fef5ff8f5\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33041}\", \"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\": \"573dad086b11e6b4c7c7a9ad36d5079658650a4b\", \"swarm_hashes_refs/heads/master(at){#845135}_without_patch\": {\"absl_hardening_tests\": \"8b37c98a73171163ead860841d8637eb4ae4d6d2\", \"accessibility_unittests\": \"0a4ac4be5734453fc4e3cad41c5c094c9304790f\", \"angle_unittests\": \"43867a53b20bbbc53ef1f939c0fb4afd499644c1\", \"app_shell_unittests\": \"2146fa084751dcc7db9ab53330c1dadfbdc8140b\", \"aura_unittests\": \"6cf665f05db377d98e6decbbed0da1650d896ca5\", \"base_unittests\": \"7e9490ec2aae91377d8cfa766f4837ec33f80497\", \"base_util_unittests\": \"f18bb6b6dd6bcc4e42cb36c91d64c2809b78e6a5\", \"blink_common_unittests\": \"5d5f3f48dfc64fee0414312edeca0b1f3b7d0ba0\", \"blink_fuzzer_unittests\": \"985ce2ca1ad72139925fafe7a79b1cb64b4361ab\", \"blink_heap_unittests\": \"019a38ec593327d4201621ced55b6ad07b32aa22\", \"blink_platform_unittests\": \"6c18ed117a7397d3e21b78a1eb98322649667b9f\", \"blink_unittests\": \"ad627ed976a910530df8a7f07cb66fc85fc8e063\", \"boringssl_crypto_tests\": \"a95fed092a3e73f89f78e3ad63bc9245ddd6cf2e\", \"boringssl_ssl_tests\": \"b2aec04b6ad7bf484ad1282185c7f423677dcce6\", \"browser_switcher_bho_unittests\": \"67432ed17db15186e456b42155c6cafc128d7480\", \"browser_tests\": \"6c1351f5befa5837ce6225bcb9cb8d9ba168659b\", \"capture_unittests\": \"0f8deee19673ed9ae802365d5cc9e724f91f91fd\", \"cast_unittests\": \"15afdd2b8a4c44b6ec1bd5a57f1a784327bcbeda\", \"cc_unittests\": \"79567179989679f2e94014aca79b6250432e0969\", \"chrome_app_unittests\": \"364c7a26ebec386880359fc672b0fb51fb9eb003\", \"chrome_cleaner_unittests\": \"6f2e1c74e2b5e1356a4909167c1d3cf7612303b8\", \"chrome_elf_unittests\": \"17f6d841b605d7201938c1e8fb893080f0005d97\", \"chromedriver_unittests\": \"7b361423ad39e279e27d95cbd25b12972701470f\", \"components_browsertests\": \"7022b25d0c43d065268305d37197e1b020b98b2c\", \"components_unittests\": \"48255c9746a6f90771763bc31270c7961e3af3f2\", \"compositor_unittests\": \"55ce3f6931823a462fd8ee4fb6545ff5ac22e2c2\", \"content_browsertests\": \"1092c6c97b508b8912f394faaad9819c9dcc936b\", \"content_unittests\": \"6cd0e184e9eb1c7f1d7ecb5ae5985b3e12ec3084\", \"courgette_unittests\": \"9e4aad88f1e2e56f6c6762298236036a6246dab2\", \"crashpad_tests\": \"1187b83970e6d6775a5337443176cb0aa80e776b\", \"cronet_tests\": \"4e6af90d130130fdd7340c01f091b0416bf479f8\", \"cronet_unittests\": \"7850252469795809e10c06ff7ebe699fac4a072d\", \"crypto_unittests\": \"d2bc23a0db5f2e5398c1cdbcd3dff7104abeecb3\", \"delayloads_unittests\": \"f43db8055bc65fe3b1606600d0502711efc94cf9\", \"device_unittests\": \"84b9ff18a56684d69155891d87d24e7cb06a682f\", \"display_unittests\": \"2b118b9c1f2089cd0e1b498fd59f1ced7894abd5\", \"elevation_service_unittests\": \"d7bc1e407fb29f81ec93c85b1de99c09919db490\", \"events_unittests\": \"dbbfcbb7825b0fdf797c05685dfcf42d384c7e57\", \"extensions_browsertests\": \"4a9633d6eb15dfac1fdca9e17d5453e9b74e68f2\", \"extensions_unittests\": \"26dcedc69d6c29a2393b44156793f27331cbe3b1\", \"filesystem_service_unittests\": \"e73100deaf110b47f67c23140658ad190d1d0f5e\", \"gcm_unit_tests\": \"321e06dcef9131a06a86cd77f340fe87bf8f082d\", \"gcp_unittests\": \"85d7686642d947e021a07dfcd1a6d89ac9629a23\", \"gfx_unittests\": \"09995d7ffb3ba3264f26da94df5bc928e3bbc180\", \"gin_unittests\": \"2ee722afe6453ff0db31e8dc87e090b28b5f0538\", \"google_apis_unittests\": \"2e9f09aad52cafc5c1e52a5861a33424cc424ff9\", \"gpu_unittests\": \"ca952117f022c6090ecfe9c08aac35d4bb119566\", \"gwp_asan_unittests\": \"a428fe9518717480d7136b391c8001b988107807\", \"headless_browsertests\": \"4a1a081e2ed57477482fab9c6cbe47a01408525f\", \"headless_unittests\": \"fe180ab7f564ed87191ee6b6eccff3e43df90290\", \"install_static_unittests\": \"787c08d90aca640cc43e97e7c147b92ab72fdbe8\", \"installer_util_unittests\": \"f33bd2a759caacc56d3692026bb97a2bb983a85c\", \"interactive_ui_tests\": \"9aa93805aeabb5d8c1aba6c8239413411b394583\", \"ipc_tests\": \"4560bc6c618a0bff54a11a3eff041b0b1ea4687f\", \"jingle_unittests\": \"681f27574b47da2a34bf70698906d20d7ab1d3ad\", \"latency_unittests\": \"10a03b85ad0fbcdcd986dc6c46883ad792d5f3ac\", \"libjingle_xmpp_unittests\": \"fb3ea5809df597f493d1bac2e581919ae1a5f0c0\", \"liburlpattern_unittests\": \"ee19e67c904ef5755073c4513f19de6589c67355\", \"media_blink_unittests\": \"3a809ee7bc1905a535185ff3199cbdc8d387a896\", \"media_unittests\": \"6f3465e0e765c96919bdbe65cb18bc640b3435e1\", \"message_center_unittests\": \"f9a9b2ae0a254457bd31ba3229be5e71ae80dc9d\", \"midi_unittests\": \"a12967970c81467172cd7a8fe32a0c8b5dafcc5a\", \"mojo_core_unittests\": \"1cacf3c6573f125a1cc44bb05d982cda0e83e2c3\", \"mojo_unittests\": \"245ef17de44c7d9c7b1893a0988fd2e591f9f3cf\", \"nacl_loader_unittests\": \"85a4fa3d66526e239ede219c4bdb05ef29f0ee8b\", \"native_theme_unittests\": \"d0808e5ee59763a76b0fd354c5a2f0f9eac3460e\", \"net_unittests\": \"0ca6e412fa970cf85e5ae979b16ffb0a7caa6ab4\", \"notification_helper_unittests\": \"3a01b1e142ee7c41a967e488e77cd3ce55cfa357\", \"pdf_unittests\": \"c41ac70177e07ec15d83c958d87cba94d3bee63e\", \"perfetto_unittests\": \"3d7c818a934a2b27d5978efa12c87ca11bd54bf4\", \"ppapi_unittests\": \"d2ff0aeb7d2790511c65b413836f0bc838ed83fc\", \"printing_unittests\": \"2b520aeff268e4c7c19f007a7f8a60f0b91b4643\", \"remoting_unittests\": \"050625b4f777d02d5bd95498e5e69cdabd191388\", \"sbox_integration_tests\": \"5f35cc3527874237066fdf81b25131cb17c0cc65\", \"sbox_unittests\": \"866ee1647f34e4d6c3f30f061fb965ab86a78826\", \"sbox_validation_tests\": \"128710c7c819fcbde9c8c2b4a3b88408d00c1806\", \"service_manager_unittests\": \"ba02af1863db1516317131a2b2806e04c2dbb2fe\", \"services_unittests\": \"835dfcbde6b557231fe8f07314dc13619ea6a134\", \"setup_unittests\": \"28f0d7027efb6a8126e777169e5c35873996b783\", \"shell_dialogs_unittests\": \"21af627684b67944c425506826b538fded1ac9ff\", \"skia_unittests\": \"013b86254582a8709c188f25446dd8163d1708a2\", \"snapshot_unittests\": \"e9f666aba406061694938ab2c7615b252b1fb5e6\", \"sql_unittests\": \"c2015dfaa8305cc12fe4d3b072a33c267db594a4\", \"storage_unittests\": \"19a2fc44510807d860bd7276958ff2b7492fea49\", \"sync_integration_tests\": \"cd287a15296a9c8dd3c7970401a1da9782ed7a5e\", \"traffic_annotation_auditor_unittests\": \"ef89721b95cf8edbd62fe26bb1b50be3dbbf4d9d\", \"ui_base_unittests\": \"8dd79fce9caa6ab1f5a4b19fd2ffc43b79ff5b9b\", \"ui_touch_selection_unittests\": \"d33017cf61c7aa1bb7927b5fcc11f9e361177be0\", \"unit_tests\": \"3e1879c4c9f5d48858ad9df167e69e0e3291f27e\", \"updater_tests\": \"43cbbb9b7fe3b70ef568a0e64d01121eaad36b88\", \"url_unittests\": \"57681ed628f80fbcd6203ffd817153c433b00918\", \"views_unittests\": \"84a354f09387d57a7d5687784a7f5f80d94770f7\", \"viz_unittests\": \"7e37371d49792adadaa9b59e92decdd91124ffa1\", \"vr_common_unittests\": \"e1d12c858fd13754aa90042ddac57ad05923204e\", \"vr_pixeltests\": \"10d80d1b681a754ca72712a18c8d2ae5bd7b2e73\", \"weblayer_browsertests\": \"dcea539f377357b51d0f94548d03c79e9f26d3b6\", \"weblayer_unittests\": \"404ef636dae8ec538fa84dd52168487525d8ece8\", \"wm_unittests\": \"46874c05ac9bab7c05d673f979d25f5e16e40c71\", \"wtf_unittests\": \"e32d7ee168aa7120ef0eeda1829274357b42c6df\", \"zlib_unittests\": \"9cb184d2fe2b218d3c427246c937d09e605968b8\", \"zucchini_unittests\": \"5863bbde755aeff68b7ca78e54fac518e0e296c8\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_27a86a15ec8feb386a5b02e9d1f544a49194bc134f7348519b0bd6cd38b20371_v2\", \"builder_91b2ef8341586e76bd64fa3093e74ce43aef344c2e2134fb3b046b47c3d4bcdb_v2\", \"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-us-central1-b-32-0-gbsn\"], \"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\": [\"5666-679c9bd\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611149909335226",
"status": "COMPLETED",
"status_changed_ts": "1611153200554684",
"tags": [
"build_address:luci.chromium.ci/win-asan/16911",
"builder:win-asan",
"buildset:commit/git/573dad086b11e6b4c7c7a9ad36d5079658650a4b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/573dad086b11e6b4c7c7a9ad36d5079658650a4b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023010653250308096",
"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/8857580591217951184/+/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:5138d61e2237a910",
"user_agent:luci-scheduler"
],
"updated_ts": "1611153200563335",
"url": "https://ci.chromium.org/b/8857580591217951184",
"utcnow_ts": "1611153201434143"
}
}
[14:33:21.443] Invocation finished in 54m55.900135769s with status SUCCEEDED