[04:43:41.371] New invocation is queued and will start shortly
[04:43:42.661] Starting the invocation (attempt 1)
[04:43:42.693] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:43:42.693] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:43:42.693] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023859668661544816",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"29d2d6a2d436015f1b9c06b78cbc3353c1f516d2\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9023859668661544816",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/29d2d6a2d436015f1b9c06b78cbc3353c1f516d2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/29d2d6a2d436015f1b9c06b78cbc3353c1f516d2",
"gitiles_ref:refs/heads/master"
]
}
[04:43:43.334] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610340222768607",
"id": "8858429606529258336",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"29d2d6a2d436015f1b9c06b78cbc3353c1f516d2\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610340223154903",
"tags": [
"build_address:luci.chromium.ci/win-asan/16750",
"builder:win-asan",
"buildset:commit/git/29d2d6a2d436015f1b9c06b78cbc3353c1f516d2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/29d2d6a2d436015f1b9c06b78cbc3353c1f516d2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023859668661544816",
"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/8858429606529258336/+/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": "1610340223155066",
"url": "https://ci.chromium.org/b/8858429606529258336",
"utcnow_ts": "1610340223317841"
}
}
[04:43:43.334] Task URL: https://ci.chromium.org/b/8858429606529258336
[04:43:43.334] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:2:0) after 1m0s
[04:43:53.464] Received PubSub notification, asking Buildbucket for the build status
[04:43:53.602] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:43.844] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:2:0)
[04:44:43.844] Timer tick, asking Buildbucket for the build status
[04:44:43.926] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:43.926] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:4:0) after 1m0s
[04:45:44.094] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:4:0)
[04:45:44.094] Timer tick, asking Buildbucket for the build status
[04:45:44.221] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:44.221] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:5:0) after 1m0s
[04:46:44.704] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:5:0)
[04:46:44.704] Timer tick, asking Buildbucket for the build status
[04:46:44.867] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:44.867] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:6:0) after 1m0s
[04:47:45.089] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:6:0)
[04:47:45.089] Timer tick, asking Buildbucket for the build status
[04:47:45.267] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:45.267] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:7:0) after 1m0s
[04:48:45.289] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:7:0)
[04:48:45.289] Timer tick, asking Buildbucket for the build status
[04:48:45.366] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:45.366] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:8:0) after 1m0s
[04:49:45.495] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:8:0)
[04:49:45.495] Timer tick, asking Buildbucket for the build status
[04:49:45.620] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:45.620] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:9:0) after 1m0s
[04:50:45.743] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:9:0)
[04:50:45.743] Timer tick, asking Buildbucket for the build status
[04:50:45.794] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:45.794] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:10:0) after 1m0s
[04:51:45.937] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:10:0)
[04:51:45.937] Timer tick, asking Buildbucket for the build status
[04:51:46.004] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:46.004] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:11:0) after 1m0s
[04:52:46.074] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:11:0)
[04:52:46.074] Timer tick, asking Buildbucket for the build status
[04:52:46.193] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:46.193] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:12:0) after 1m0s
[04:53:46.218] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:12:0)
[04:53:46.218] Timer tick, asking Buildbucket for the build status
[04:53:46.299] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:46.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:13:0) after 1m0s
[04:54:46.389] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:13:0)
[04:54:46.389] Timer tick, asking Buildbucket for the build status
[04:54:46.475] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:46.475] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:14:0) after 1m0s
[04:55:46.581] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:14:0)
[04:55:46.581] Timer tick, asking Buildbucket for the build status
[04:55:46.649] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:46.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:15:0) after 1m0s
[04:56:46.729] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:15:0)
[04:56:46.729] Timer tick, asking Buildbucket for the build status
[04:56:46.788] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:46.788] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:16:0) after 1m0s
[04:57:46.786] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:16:0)
[04:57:46.786] Timer tick, asking Buildbucket for the build status
[04:57:47.123] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:47.123] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:17:0) after 1m0s
[04:58:47.149] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:17:0)
[04:58:47.150] Timer tick, asking Buildbucket for the build status
[04:58:47.218] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:47.218] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:18:0) after 1m0s
[04:59:47.266] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:18:0)
[04:59:47.266] Timer tick, asking Buildbucket for the build status
[04:59:47.517] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:47.517] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:19:0) after 1m0s
[05:00:47.651] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:19:0)
[05:00:47.651] Timer tick, asking Buildbucket for the build status
[05:00:47.742] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:47.742] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:20:0) after 1m0s
[05:01:47.814] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:20:0)
[05:01:47.814] Timer tick, asking Buildbucket for the build status
[05:01:47.955] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:47.955] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:21:0) after 1m0s
[05:02:48.094] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:21:0)
[05:02:48.094] Timer tick, asking Buildbucket for the build status
[05:02:48.847] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:48.847] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:22:0) after 1m0s
[05:03:49.131] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:22:0)
[05:03:49.131] Timer tick, asking Buildbucket for the build status
[05:03:49.304] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:49.304] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:23:0) after 1m0s
[05:04:49.387] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:23:0)
[05:04:49.387] Timer tick, asking Buildbucket for the build status
[05:04:49.535] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:49.535] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:24:0) after 1m0s
[05:05:49.711] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:24:0)
[05:05:49.711] Timer tick, asking Buildbucket for the build status
[05:05:49.814] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:49.814] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:25:0) after 1m0s
[05:06:49.961] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:25:0)
[05:06:49.961] Timer tick, asking Buildbucket for the build status
[05:06:50.123] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:50.123] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:26:0) after 1m0s
[05:07:50.262] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:26:0)
[05:07:50.262] Timer tick, asking Buildbucket for the build status
[05:07:50.408] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:50.408] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:27:0) after 1m0s
[05:08:50.499] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:27:0)
[05:08:50.499] Timer tick, asking Buildbucket for the build status
[05:08:50.644] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:50.644] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:28:0) after 1m0s
[05:09:50.711] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:28:0)
[05:09:50.711] Timer tick, asking Buildbucket for the build status
[05:09:50.847] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:50.847] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:29:0) after 1m0s
[05:10:50.863] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:29:0)
[05:10:50.863] Timer tick, asking Buildbucket for the build status
[05:10:50.987] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:50.987] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:30:0) after 1m0s
[05:11:51.040] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:30:0)
[05:11:51.040] Timer tick, asking Buildbucket for the build status
[05:11:51.328] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:51.328] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:31:0) after 1m0s
[05:12:51.407] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:31:0)
[05:12:51.407] Timer tick, asking Buildbucket for the build status
[05:12:51.701] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:51.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:32:0) after 1m0s
[05:13:51.801] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:32:0)
[05:13:51.801] Timer tick, asking Buildbucket for the build status
[05:13:52.000] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:52.000] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:33:0) after 1m0s
[05:14:52.165] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:33:0)
[05:14:52.165] Timer tick, asking Buildbucket for the build status
[05:14:52.279] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:52.279] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:34:0) after 1m0s
[05:15:52.258] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:34:0)
[05:15:52.258] Timer tick, asking Buildbucket for the build status
[05:15:52.370] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:52.370] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:35:0) after 1m0s
[05:16:52.394] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:35:0)
[05:16:52.394] Timer tick, asking Buildbucket for the build status
[05:16:52.793] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:52.793] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:36:0) after 1m0s
[05:17:52.831] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:36:0)
[05:17:52.831] Timer tick, asking Buildbucket for the build status
[05:17:52.916] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:52.916] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:37:0) after 1m0s
[05:18:53.016] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:37:0)
[05:18:53.016] Timer tick, asking Buildbucket for the build status
[05:18:53.664] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:53.664] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:38:0) after 1m0s
[05:19:53.695] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:38:0)
[05:19:53.695] Timer tick, asking Buildbucket for the build status
[05:19:53.925] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:53.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:39:0) after 1m0s
[05:20:53.928] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:39:0)
[05:20:53.928] Timer tick, asking Buildbucket for the build status
[05:20:54.479] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:54.479] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:40:0) after 1m0s
[05:21:54.534] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:40:0)
[05:21:54.534] Timer tick, asking Buildbucket for the build status
[05:21:54.628] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:54.628] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:41:0) after 1m0s
[05:22:54.633] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:41:0)
[05:22:54.633] Timer tick, asking Buildbucket for the build status
[05:22:54.788] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:54.788] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:42:0) after 1m0s
[05:23:54.862] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:42:0)
[05:23:54.862] Timer tick, asking Buildbucket for the build status
[05:23:55.612] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:55.612] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:43:0) after 1m0s
[05:24:55.635] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:43:0)
[05:24:55.635] Timer tick, asking Buildbucket for the build status
[05:24:55.711] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:55.711] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:44:0) after 1m0s
[05:25:55.729] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:44:0)
[05:25:55.729] Timer tick, asking Buildbucket for the build status
[05:25:55.798] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:55.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:45:0) after 1m0s
[05:26:55.819] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:45:0)
[05:26:55.819] Timer tick, asking Buildbucket for the build status
[05:26:55.946] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:55.946] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:46:0) after 1m0s
[05:27:55.964] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:46:0)
[05:27:55.964] Timer tick, asking Buildbucket for the build status
[05:27:56.072] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:56.072] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:47:0) after 1m0s
[05:28:56.098] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:47:0)
[05:28:56.099] Timer tick, asking Buildbucket for the build status
[05:28:56.808] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:56.808] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:48:0) after 1m0s
[05:29:56.881] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:48:0)
[05:29:56.881] Timer tick, asking Buildbucket for the build status
[05:29:56.982] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:56.982] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:49:0) after 1m0s
[05:30:57.003] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:49:0)
[05:30:57.003] Timer tick, asking Buildbucket for the build status
[05:30:57.118] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:57.118] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:50:0) after 1m0s
[05:31:57.185] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:50:0)
[05:31:57.185] Timer tick, asking Buildbucket for the build status
[05:31:57.396] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:57.396] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:51:0) after 1m0s
[05:32:57.418] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:51:0)
[05:32:57.418] Timer tick, asking Buildbucket for the build status
[05:32:57.965] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:57.965] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:52:0) after 1m0s
[05:33:58.002] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:52:0)
[05:33:58.002] Timer tick, asking Buildbucket for the build status
[05:33:58.502] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:58.502] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:53:0) after 1m0s
[05:34:58.544] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:53:0)
[05:34:58.544] Timer tick, asking Buildbucket for the build status
[05:34:59.005] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:59.005] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:54:0) after 1m0s
[05:35:59.057] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:54:0)
[05:35:59.057] Timer tick, asking Buildbucket for the build status
[05:35:59.121] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:59.121] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:55:0) after 1m0s
[05:36:59.161] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:55:0)
[05:36:59.161] Timer tick, asking Buildbucket for the build status
[05:36:59.704] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:59.704] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:56:0) after 1m0s
[05:37:59.719] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:56:0)
[05:37:59.719] Timer tick, asking Buildbucket for the build status
[05:37:59.818] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:59.818] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:57:0) after 1m0s
[05:38:59.900] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:57:0)
[05:38:59.900] Timer tick, asking Buildbucket for the build status
[05:39:00.127] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:00.127] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:58:0) after 1m0s
[05:40:00.183] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:58:0)
[05:40:00.183] Timer tick, asking Buildbucket for the build status
[05:40:00.300] Build 8858429606529258336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:00.300] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023859668661544816:59:0) after 1m0s
[05:40:18.269] Received PubSub notification, asking Buildbucket for the build status
[05:40:18.352] Build 8858429606529258336: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:40:18.352] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610343617272900",
"created_by": "project:chromium",
"created_ts": "1610340222768607",
"id": "8858429606529258336",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"29d2d6a2d436015f1b9c06b78cbc3353c1f516d2\"}}",
"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\": \"29d2d6a2d436015f1b9c06b78cbc3353c1f516d2\", \"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-1-ervs\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16750, \"clang_revision\": \"llvmorg-12-init-15676-g5c951623-1\", \"got_angle_revision\": \"0b90eef09f607336330f318bc8e65fa981634160\", \"got_dawn_revision\": \"3a0405597374c9ae12fb93e33aac35826455a958\", \"got_nacl_revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"got_revision\": \"29d2d6a2d436015f1b9c06b78cbc3353c1f516d2\", \"got_revision_cp\": \"refs/heads/master@{#841927}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"348ba200aa29d5b3a36d696d48d8f619e055219f\", \"got_v8_revision\": \"5d60a181de0863d8d00ae42ec0ee3521f51b501a\", \"got_v8_revision_cp\": \"refs/heads/8.9.229@{#1}\", \"got_webrtc_revision\": \"952be209246ab9ba702159cb246eac0e232b15ae\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32931}\", \"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\": \"29d2d6a2d436015f1b9c06b78cbc3353c1f516d2\", \"swarm_hashes_refs/heads/master(at){#841927}_without_patch\": {\"absl_hardening_tests\": \"263a2bb1f83e7e3466c0b288cc1d2659da464fa3\", \"accessibility_unittests\": \"f33e36cc8e0c75690d34c630d38f3755b67f8b0f\", \"angle_unittests\": \"915f84af2f71a9f12f648dfed609c440ebc1b97d\", \"app_shell_unittests\": \"4374c8d42be3ba7509e494585523a6168866cae7\", \"aura_unittests\": \"d518d8a49e7a6041b1217a4a5b99a6419f171b00\", \"base_unittests\": \"28e1bd110f60db17f89395e138cb293d53003d9f\", \"base_util_unittests\": \"cf51485a34fa795fc8724b63d7ab7d89d13eb817\", \"blink_common_unittests\": \"d2f0dbbebca258b691314621e929cb4b55416abe\", \"blink_fuzzer_unittests\": \"837bd504691518094231f6a160482e6d658dae12\", \"blink_heap_unittests\": \"062ef14d91ebc63d6ec7498bb803017fbf99ec9b\", \"blink_platform_unittests\": \"eb8f67bae221f17ca3e2f2c47e04c19289641472\", \"blink_unittests\": \"b7d39361cc991745c907804f5c5a0f58a5b5d3f4\", \"boringssl_crypto_tests\": \"7ce3b570ffdbdf77e800d94b7e4eb4f2fc2e6dce\", \"boringssl_ssl_tests\": \"b7fa7f4d2c184bb8778f54111768d535187d006b\", \"browser_switcher_bho_unittests\": \"13203f7ff96092eaa32a638298cc9cc849de9e64\", \"browser_tests\": \"af4741046cf0a7d57d1a842f8e5e3285ac8a133b\", \"capture_unittests\": \"6cfc8bf5179454e0aa30608c6424b53f8d5cb431\", \"cast_unittests\": \"0c68476303f9cd3830f095d499623d578d3a169a\", \"cc_unittests\": \"068b67d3f6462352ab92d588e510bc45d1a5fa0b\", \"chrome_app_unittests\": \"596af04095a77d3ec607ea39543dab304fbe1288\", \"chrome_cleaner_unittests\": \"33fb9f7515eabf65196f066405112e70fe959a0b\", \"chrome_elf_unittests\": \"ffe3875277d4cd8d7eb9a31ed89d78d46c9742dc\", \"chromedriver_unittests\": \"f06bd06e922dd46916420a4514c785297d811b62\", \"components_browsertests\": \"ac95632e2a94fc00e3d98b6428d52259314e38d8\", \"components_unittests\": \"ceb928dc395f597080b91786fbcc321238f347e4\", \"compositor_unittests\": \"11f513d143bc3ebe79cc56b31a71e53ef35c7953\", \"content_browsertests\": \"229b63f41557159afd0fd501ac5cf916f157f9bf\", \"content_unittests\": \"5fa54a07f7d9148b124b3619f235e14e46553cf4\", \"courgette_unittests\": \"4187f2d13b5f861ae1d1b51514badda04ade0ed8\", \"crashpad_tests\": \"d502bcec8b6a598327c5385e6bf6e118c8f779ef\", \"cronet_tests\": \"5cf4621c3e78b79c215c25f6d10919a6f984a2f9\", \"cronet_unittests\": \"88897ef6ba45d74c27022bfb80d00b9fa3a2cc34\", \"crypto_unittests\": \"57026856aad1fbb82ab97a70a6fb43468277bc09\", \"delayloads_unittests\": \"a2d0b684660918dc543f0601ed10e4b3425ab5ab\", \"device_unittests\": \"ce59f24a6b59809a312c7d54d9c0490693adced9\", \"display_unittests\": \"dd7e5dd916b3ef27809f0746165e0592f55716c4\", \"elevation_service_unittests\": \"962e75237386f62e47ab4faf69b4e70084c1bb26\", \"events_unittests\": \"599029480b59325c0f6f19b4575f5528506edc6a\", \"extensions_browsertests\": \"58eada04b2f02ca46a520a3e74ab3c589883d4fa\", \"extensions_unittests\": \"6ffdce51ac431dbbfe95c4594d210f9fc7074ec8\", \"filesystem_service_unittests\": \"daf573cda6521b0b81e4be5df69fbfe114e522fe\", \"gcm_unit_tests\": \"64e77f0182af2e3745e85b5d11629a25137b704d\", \"gcp_unittests\": \"1b54436beef695efbdbb4fd20f97ea7f50b149f7\", \"gfx_unittests\": \"5b721e6b46c8c8e4082b7ca6f7ced00a070f3fc1\", \"gin_unittests\": \"3b7aebbd9e419f002a6b10290f3aa8332baf4c19\", \"google_apis_unittests\": \"65670a296ff9aa662e8ba2ae6e72af0255ced544\", \"gpu_unittests\": \"e6259e2dc1991b2f8a53367bb5b5dedffe7b5c50\", \"gwp_asan_unittests\": \"c532943201f95285ffff105b15098c14aba5a9a0\", \"headless_browsertests\": \"9cbd63c11bea6e1a3e5554790191300a8a8754e8\", \"headless_unittests\": \"fd281b2612a802aabab9ceb53ad7c712a777ca9b\", \"install_static_unittests\": \"0b8a2358543b31381be64a3861e5617ebee4ada7\", \"installer_util_unittests\": \"f496a2ba8f4d9fae797acb20ddd3e4d41218b1c7\", \"interactive_ui_tests\": \"a136fe667c470e245b6d24e6de560c250a75ebf6\", \"ipc_tests\": \"86128bd4c41013058f0c404a746d7b5554cd8129\", \"jingle_unittests\": \"8b063d84a855244d95cf5c164d46aaf8985e6084\", \"latency_unittests\": \"160d897d3c8eb17c21126ef223a699edad90d500\", \"libjingle_xmpp_unittests\": \"951a8696c0ce92c1a9bfffb15adccd6391095199\", \"liburlpattern_unittests\": \"c873b6028142a0327d2a6b995de111159dbf54df\", \"media_blink_unittests\": \"0a232a7e06e640cc6ae83287c05730076001bf17\", \"media_unittests\": \"2d4c004f1658d41df7723e15b0cece32a926d765\", \"message_center_unittests\": \"7aeb3497db5d5cfb0713f530a0b99c6207869511\", \"midi_unittests\": \"0d15bf6b6896fde1502b5071c9b1450bc418e55e\", \"mojo_core_unittests\": \"61fdd7110655a1bd4e8e3a7b4d4a9238db7eb6bc\", \"mojo_unittests\": \"6e45f749a137acca3790cee89addc8e9d5c1997f\", \"nacl_loader_unittests\": \"ff2dea9b2d94ec3da1a81db394d362813921d9aa\", \"native_theme_unittests\": \"2fc380bcfc887940a07369c0a0c73b93d909b739\", \"net_unittests\": \"22d1bfd47a1b93d0fb3a120a133c4485f1380572\", \"notification_helper_unittests\": \"c032e972176432122c3ae6ecaf958064eb210df1\", \"pdf_unittests\": \"cb7fa62de8c9d4b2eaa4847b9a722663e9906e4f\", \"perfetto_unittests\": \"d956693d0cab28655fdd0ccff1cd2d952921a9bb\", \"ppapi_unittests\": \"00e2ec4808fb96a513539ee17a6a3959ea881550\", \"printing_unittests\": \"f5ebba9c14926812e54fad06bf2f62a530f5367a\", \"remoting_unittests\": \"ca99a15d3d66d2fa72e71004ec7f19721b7571ec\", \"sbox_integration_tests\": \"1638ef3ea4e52bbafba3ba62b0e5fc8c708a9cfa\", \"sbox_unittests\": \"327163edbf124562c39110e07862fa88299ac951\", \"sbox_validation_tests\": \"7af29d15a8af9a0045e2da0349b0d275ba013016\", \"service_manager_unittests\": \"7a61a7208a129185a3028b6c68832b69ab06b5fc\", \"services_unittests\": \"2efcbaff80710115be7297686c3e5be9d8eee06e\", \"setup_unittests\": \"47642bd26d53f17f09885b81eeab465412adca58\", \"shell_dialogs_unittests\": \"1357e312d481deb8dcbfbaf4cd2b71d4820c978e\", \"skia_unittests\": \"49c8b39eb161cd8d97e6a6093f4ad8bfb7f1da36\", \"snapshot_unittests\": \"8144abb088c2e1d8aa0baa608d48191e01efaa9e\", \"sql_unittests\": \"b5c57c6691b922b73882152601541fcd9b1a411d\", \"storage_unittests\": \"a24baced341fd172d17ffd51f76d7a034c64dc48\", \"sync_integration_tests\": \"bef446863e6a224ed1412b6a971147ad43a326e8\", \"traffic_annotation_auditor_unittests\": \"517dafc1a8523311d01e117f81a0e62291b70bae\", \"ui_base_unittests\": \"8f5cf75e2a3d9183e5446a3e3618b1d5f40c86bd\", \"ui_touch_selection_unittests\": \"0994866a61cc3e72a8ebed6acabf8fc6b8b15f04\", \"unit_tests\": \"5c196f66dee797ec3e255770faf11b37b447938e\", \"updater_tests\": \"90cdcbfa0e9c2076b7e5f9127c1bfd3099072fbd\", \"url_unittests\": \"7b76050991be0b0e9a42e538d9c72856f69986a8\", \"views_unittests\": \"31200dcdabef43d077ea57840a85104e0e479774\", \"viz_unittests\": \"ffbbbd0c190cb5fbbce931daa934eff4c6ce29d2\", \"vr_common_unittests\": \"ff35518a705469aaae005ceb7e063e95d19ed671\", \"vr_pixeltests\": \"fc202a9d4a8ec85eb4498f45544c83392397bc59\", \"weblayer_browsertests\": \"61187bdfc76a0336d692437751f9131825d1acfa\", \"weblayer_unittests\": \"5c7967801c08517feb14f873fe390b789762478e\", \"wm_unittests\": \"b3f1d128735202fe9bc56771bd5c609d8953f8f4\", \"wtf_unittests\": \"f20af0af1b3b7c92cd9a1a10e93c56edf9b7290b\", \"zlib_unittests\": \"830d0834bbb812f50e90c5b7c59d5ad437289cf9\", \"zucchini_unittests\": \"00ebe21a752a5d428de2a77cdb979ead2f61ca14\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"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-1-ervs\"], \"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\": [\"5657-0417a3f\"], \"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": "1610340232506760",
"status": "COMPLETED",
"status_changed_ts": "1610343617610726",
"tags": [
"build_address:luci.chromium.ci/win-asan/16750",
"builder:win-asan",
"buildset:commit/git/29d2d6a2d436015f1b9c06b78cbc3353c1f516d2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/29d2d6a2d436015f1b9c06b78cbc3353c1f516d2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023859668661544816",
"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/8858429606529258336/+/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:510893602a6e1010",
"user_agent:luci-scheduler"
],
"updated_ts": "1610343617623062",
"url": "https://ci.chromium.org/b/8858429606529258336",
"utcnow_ts": "1610343618343274"
}
}
[05:40:18.352] Invocation finished in 56m37.001457833s with status SUCCEEDED