[16:31:01.111] New invocation is queued and will start shortly
[16:31:02.245] Starting the invocation (attempt 1)
[16:31:02.325] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:31:02.325] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:31:02.325] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9027167255130842128",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"f1a8be8ac2883a562b2b3a2f8522f0ba962146b4\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9027167255130842128",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/f1a8be8ac2883a562b2b3a2f8522f0ba962146b4",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f1a8be8ac2883a562b2b3a2f8522f0ba962146b4",
"gitiles_ref:refs/heads/master"
]
}
[16:31:04.347] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1607185862611599",
"id": "8861737192885009920",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f1a8be8ac2883a562b2b3a2f8522f0ba962146b4\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1607185863841900",
"tags": [
"build_address:luci.chromium.ci/win-asan/16045",
"builder:win-asan",
"buildset:commit/git/f1a8be8ac2883a562b2b3a2f8522f0ba962146b4",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f1a8be8ac2883a562b2b3a2f8522f0ba962146b4",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027167255130842128",
"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/8861737192885009920/+/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": "1607185863842042",
"url": "https://ci.chromium.org/b/8861737192885009920",
"utcnow_ts": "1607185864330537"
}
}
[16:31:04.347] Task URL: https://ci.chromium.org/b/8861737192885009920
[16:31:04.348] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:2:0) after 1m0s
[16:31:09.701] Received PubSub notification, asking Buildbucket for the build status
[16:31:09.767] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:04.867] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:2:0)
[16:32:04.867] Timer tick, asking Buildbucket for the build status
[16:32:05.157] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:05.157] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:4:0) after 1m0s
[16:33:05.259] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:4:0)
[16:33:05.259] Timer tick, asking Buildbucket for the build status
[16:33:06.137] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:06.137] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:5:0) after 1m0s
[16:34:06.202] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:5:0)
[16:34:06.203] Timer tick, asking Buildbucket for the build status
[16:34:06.309] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:06.309] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:6:0) after 1m0s
[16:35:06.371] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:6:0)
[16:35:06.371] Timer tick, asking Buildbucket for the build status
[16:35:07.832] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:07.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:7:0) after 1m0s
[16:36:07.887] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:7:0)
[16:36:07.887] Timer tick, asking Buildbucket for the build status
[16:36:07.983] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:07.983] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:8:0) after 1m0s
[16:37:08.051] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:8:0)
[16:37:08.051] Timer tick, asking Buildbucket for the build status
[16:37:08.519] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:08.519] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:9:0) after 1m0s
[16:38:08.546] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:9:0)
[16:38:08.546] Timer tick, asking Buildbucket for the build status
[16:38:08.672] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:08.672] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:10:0) after 1m0s
[16:39:08.734] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:10:0)
[16:39:08.734] Timer tick, asking Buildbucket for the build status
[16:39:09.493] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:09.493] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:11:0) after 1m0s
[16:40:09.544] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:11:0)
[16:40:09.544] Timer tick, asking Buildbucket for the build status
[16:40:09.678] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:09.678] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:12:0) after 1m0s
[16:41:09.874] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:12:0)
[16:41:09.874] Timer tick, asking Buildbucket for the build status
[16:41:09.989] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:09.989] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:13:0) after 1m0s
[16:42:10.115] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:13:0)
[16:42:10.115] Timer tick, asking Buildbucket for the build status
[16:42:10.367] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:10.367] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:14:0) after 1m0s
[16:43:10.476] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:14:0)
[16:43:10.476] Timer tick, asking Buildbucket for the build status
[16:43:11.042] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:11.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:15:0) after 1m0s
[16:44:11.145] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:15:0)
[16:44:11.145] Timer tick, asking Buildbucket for the build status
[16:44:11.375] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:11.375] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:16:0) after 1m0s
[16:45:11.403] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:16:0)
[16:45:11.403] Timer tick, asking Buildbucket for the build status
[16:45:11.638] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:11.638] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:17:0) after 1m0s
[16:46:11.765] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:17:0)
[16:46:11.765] Timer tick, asking Buildbucket for the build status
[16:46:12.160] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:12.160] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:18:0) after 1m0s
[16:47:12.186] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:18:0)
[16:47:12.186] Timer tick, asking Buildbucket for the build status
[16:47:14.526] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:14.526] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:19:0) after 1m0s
[16:48:14.640] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:19:0)
[16:48:14.640] Timer tick, asking Buildbucket for the build status
[16:48:14.867] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:14.867] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:20:0) after 1m0s
[16:49:14.967] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:20:0)
[16:49:14.967] Timer tick, asking Buildbucket for the build status
[16:49:15.063] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:15.063] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:21:0) after 1m0s
[16:50:15.191] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:21:0)
[16:50:15.191] Timer tick, asking Buildbucket for the build status
[16:50:24.872] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:24.872] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:22:0) after 1m0s
[16:51:24.942] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:22:0)
[16:51:24.942] Timer tick, asking Buildbucket for the build status
[16:51:25.331] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:25.331] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:23:0) after 1m0s
[16:52:25.306] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:23:0)
[16:52:25.306] Timer tick, asking Buildbucket for the build status
[16:52:25.636] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:25.636] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:24:0) after 1m0s
[16:53:25.667] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:24:0)
[16:53:25.667] Timer tick, asking Buildbucket for the build status
[16:53:26.855] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:26.855] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:25:0) after 1m0s
[16:54:26.997] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:25:0)
[16:54:26.997] Timer tick, asking Buildbucket for the build status
[16:54:27.154] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:27.154] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:26:0) after 1m0s
[16:55:27.331] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:26:0)
[16:55:27.331] Timer tick, asking Buildbucket for the build status
[16:55:27.467] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:27.467] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:27:0) after 1m0s
[16:56:27.727] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:27:0)
[16:56:27.727] Timer tick, asking Buildbucket for the build status
[16:56:27.928] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:27.928] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:28:0) after 1m0s
[16:57:28.126] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:28:0)
[16:57:28.126] Timer tick, asking Buildbucket for the build status
[16:57:28.453] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:28.453] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:29:0) after 1m0s
[16:58:28.472] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:29:0)
[16:58:28.472] Timer tick, asking Buildbucket for the build status
[16:58:28.573] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:28.573] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:30:0) after 1m0s
[16:59:33.075] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:30:0)
[16:59:33.075] Timer tick, asking Buildbucket for the build status
[16:59:35.656] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:35.656] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:31:0) after 1m0s
[17:00:35.782] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:31:0)
[17:00:35.782] Timer tick, asking Buildbucket for the build status
[17:00:35.956] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:35.956] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:32:0) after 1m0s
[17:01:35.978] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:32:0)
[17:01:35.978] Timer tick, asking Buildbucket for the build status
[17:01:36.125] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:36.125] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:33:0) after 1m0s
[17:02:36.184] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:33:0)
[17:02:36.184] Timer tick, asking Buildbucket for the build status
[17:02:36.382] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:36.382] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:34:0) after 1m0s
[17:03:36.762] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:34:0)
[17:03:36.762] Timer tick, asking Buildbucket for the build status
[17:03:36.959] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:36.959] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:35:0) after 1m0s
[17:04:37.222] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:35:0)
[17:04:37.222] Timer tick, asking Buildbucket for the build status
[17:04:37.491] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:37.491] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:36:0) after 1m0s
[17:05:37.580] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:36:0)
[17:05:37.580] Timer tick, asking Buildbucket for the build status
[17:05:37.826] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:37.826] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:37:0) after 1m0s
[17:06:37.910] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:37:0)
[17:06:37.910] Timer tick, asking Buildbucket for the build status
[17:06:38.045] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:38.045] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:38:0) after 1m0s
[17:07:38.065] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:38:0)
[17:07:38.065] Timer tick, asking Buildbucket for the build status
[17:07:39.098] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:39.098] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:39:0) after 1m0s
[17:08:39.109] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:39:0)
[17:08:39.109] Timer tick, asking Buildbucket for the build status
[17:08:39.213] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:39.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:40:0) after 1m0s
[17:09:39.269] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:40:0)
[17:09:39.269] Timer tick, asking Buildbucket for the build status
[17:09:39.659] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:39.659] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:41:0) after 1m0s
[17:10:39.841] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:41:0)
[17:10:39.841] Timer tick, asking Buildbucket for the build status
[17:10:43.861] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:43.861] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:42:0) after 1m0s
[17:11:43.913] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:42:0)
[17:11:43.913] Timer tick, asking Buildbucket for the build status
[17:11:44.047] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:44.047] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:43:0) after 1m0s
[17:12:44.072] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:43:0)
[17:12:44.072] Timer tick, asking Buildbucket for the build status
[17:12:44.288] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:44.288] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:44:0) after 1m0s
[17:13:44.310] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:44:0)
[17:13:44.310] Timer tick, asking Buildbucket for the build status
[17:13:44.420] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:44.420] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:45:0) after 1m0s
[17:14:44.448] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:45:0)
[17:14:44.448] Timer tick, asking Buildbucket for the build status
[17:14:46.051] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:46.051] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:46:0) after 1m0s
[17:15:46.114] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:46:0)
[17:15:46.114] Timer tick, asking Buildbucket for the build status
[17:15:47.231] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:47.231] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:47:0) after 1m0s
[17:16:47.296] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:47:0)
[17:16:47.296] Timer tick, asking Buildbucket for the build status
[17:16:55.754] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:55.754] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:48:0) after 1m0s
[17:17:55.936] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:48:0)
[17:17:55.936] Timer tick, asking Buildbucket for the build status
[17:17:56.054] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:56.054] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:49:0) after 1m0s
[17:18:56.054] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:49:0)
[17:18:56.054] Timer tick, asking Buildbucket for the build status
[17:18:56.150] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:56.150] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:50:0) after 1m0s
[17:19:56.161] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:50:0)
[17:19:56.161] Timer tick, asking Buildbucket for the build status
[17:19:56.548] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:56.548] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:51:0) after 1m0s
[17:20:56.655] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:51:0)
[17:20:56.655] Timer tick, asking Buildbucket for the build status
[17:20:56.761] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:56.761] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:52:0) after 1m0s
[17:21:56.788] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:52:0)
[17:21:56.788] Timer tick, asking Buildbucket for the build status
[17:21:56.918] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:56.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:53:0) after 1m0s
[17:22:57.005] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:53:0)
[17:22:57.005] Timer tick, asking Buildbucket for the build status
[17:22:57.162] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:57.162] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:54:0) after 1m0s
[17:23:57.199] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:54:0)
[17:23:57.199] Timer tick, asking Buildbucket for the build status
[17:23:57.757] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:57.757] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:55:0) after 1m0s
[17:24:57.884] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:55:0)
[17:24:57.884] Timer tick, asking Buildbucket for the build status
[17:24:58.017] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:58.017] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:56:0) after 1m0s
[17:25:58.010] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:56:0)
[17:25:58.010] Timer tick, asking Buildbucket for the build status
[17:25:58.177] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:58.177] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:57:0) after 1m0s
[17:26:58.235] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:57:0)
[17:26:58.235] Timer tick, asking Buildbucket for the build status
[17:27:02.447] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:02.447] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:58:0) after 1m0s
[17:28:02.469] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:58:0)
[17:28:02.469] Timer tick, asking Buildbucket for the build status
[17:28:03.473] Build 8861737192885009920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:03.473] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027167255130842128:59:0) after 1m0s
[17:28:34.421] Received PubSub notification, asking Buildbucket for the build status
[17:28:35.067] Build 8861737192885009920: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[17:28:35.067] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1607189313507400",
"created_by": "project:chromium",
"created_ts": "1607185862611599",
"id": "8861737192885009920",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f1a8be8ac2883a562b2b3a2f8522f0ba962146b4\"}}",
"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\": \"f1a8be8ac2883a562b2b3a2f8522f0ba962146b4\", \"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-6-7lfd\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16045, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"251ba5cb119ff2fed0e861cbc9b096c45004c1fa\", \"got_dawn_revision\": \"c532048062befdee8bb57d18f01d3a3c403d49de\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"f1a8be8ac2883a562b2b3a2f8522f0ba962146b4\", \"got_revision_cp\": \"refs/heads/master@{#834028}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"f36e874ade1dbc6f5ddfc857753a892a8bb358c3\", \"got_v8_revision\": \"61f3e61408129afc0d9e1268d3354a283ea3c881\", \"got_v8_revision_cp\": \"refs/heads/8.9.100@{#1}\", \"got_webrtc_revision\": \"6c80aebd00360ee00c84c7b14422c5fe475d8916\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32774}\", \"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\": \"f1a8be8ac2883a562b2b3a2f8522f0ba962146b4\", \"swarm_hashes_refs/heads/master(at){#834028}_without_patch\": {\"absl_hardening_tests\": \"b7756871b76c5c8f76958c3b65646b96710a3dd1\", \"accessibility_unittests\": \"ad49ae7e38e70e752ff64c114432e8efd94c66d2\", \"angle_unittests\": \"b1485a29af17d361aec21fe0249230f2f510b4b0\", \"app_shell_unittests\": \"ac5e0308c0d11c1971066c1881563831b1427f35\", \"aura_unittests\": \"2e79ce7e59a5952569fe84d14653611f642e1205\", \"base_unittests\": \"470147db8cd2774dc39c47a92a85a9f7ee7a99a1\", \"base_util_unittests\": \"1699349db27a792e99eb8fc6ebb11a12a3bf5e29\", \"blink_common_unittests\": \"978840fcddd74acfeeb863bfc04d731794ebe478\", \"blink_fuzzer_unittests\": \"9c8e1580510fc6607264548b69ed79e03cb646cf\", \"blink_heap_unittests\": \"9cc774ad8c43a7e16c4a900bb01d85794dbb94f0\", \"blink_platform_unittests\": \"858c77b3c890567f5f1db65caf6183e283c25c61\", \"blink_unittests\": \"46e8f47502f31942171c5f6365ef36b11676f708\", \"boringssl_crypto_tests\": \"2a99d76fb3aa71918e26b158a081fb316dc1fc01\", \"boringssl_ssl_tests\": \"31d5e8b91d72b678d61592480e3cf8833a9a50ff\", \"browser_switcher_bho_unittests\": \"87964c28a7f623db64c5a68c90ef1bcab6e3b070\", \"browser_tests\": \"9b18d87e3226968b201ac86db66dc37581369e91\", \"capture_unittests\": \"206a4c5a1a59da81cc129ea37022038738d65537\", \"cast_unittests\": \"48bee35bc2c61a024199349863c9690fff45d288\", \"cc_unittests\": \"bdbc51ef54161708865cef51b2845d1fd567828a\", \"chrome_app_unittests\": \"302a5913f16c4815cb8b10e6a2fa8c786f2a8568\", \"chrome_cleaner_unittests\": \"18dcb7e69469e53e9819614cdaf892c4dbc36453\", \"chrome_elf_unittests\": \"2ac2ecc5673ad21432506478bcb2ee8e3c774a10\", \"chromedriver_unittests\": \"d28ab5d14383e35163f3a00cb1c3d97f85c3df27\", \"components_browsertests\": \"78e4d54b2d3972c4e151901556c772d1a5d71e11\", \"components_unittests\": \"2802cc95b744ea6b4783daa61ea90f466f7518b0\", \"compositor_unittests\": \"e477c59ec71f4d2f509bc796be3178fa3f22d0ea\", \"content_browsertests\": \"b5bf89aa26b40fa33b9876c584493141ec026669\", \"content_unittests\": \"c6ac8a6597deb3ce83ef4f043ca9cbd659cd1c67\", \"courgette_unittests\": \"ecd1a3d3372684f2322baa183813c64a0438d2f1\", \"crashpad_tests\": \"a6d764f38b76722e3e5170d9b26abbac3f0ff477\", \"cronet_tests\": \"4565c0080c0172ea91e84a84ff4a741f630e8892\", \"cronet_unittests\": \"03caadc32f28e399f48e5d2340b2306a52c914e4\", \"crypto_unittests\": \"796e65fb3824b4989ad7e61b47c4fe26f4e2bdbd\", \"delayloads_unittests\": \"50d5044b100b83e537f7a25d7cfb0a5ef77c2e7f\", \"device_unittests\": \"a27cd06a91f72cc2bc8342bb4423a8575784c0d4\", \"display_unittests\": \"d84b670b74fb98205884ca4994ebdc283097ef47\", \"elevation_service_unittests\": \"f8945f6799058f7e2b781d6f259ef5717df64874\", \"events_unittests\": \"265aa501c3a8832fdda9ef4d6f1c105d68e5fec4\", \"extensions_browsertests\": \"0b5ea3d790e82672cf26ca5af983cf26ed0dc127\", \"extensions_unittests\": \"32903c98671ccb9ce0d135c097648560afb44ad0\", \"filesystem_service_unittests\": \"87c588402629f4887312cd409b1dfd56b9f0cf4e\", \"gcm_unit_tests\": \"fd57c1a67e0c934968abdb388236ad2da5e4f6eb\", \"gcp_unittests\": \"62cde27d3b28adae8e0f2b3744a8e79db98b22f7\", \"gfx_unittests\": \"c4046080910713aefe5aab1615546c3050746f0f\", \"gin_unittests\": \"fbf3943c36bed4e1976df797b070566cc15a4047\", \"google_apis_unittests\": \"d5f68ce071865e830f0111fdee39beb5f3a614e1\", \"gpu_unittests\": \"8fb5b72b5b873c35257e968e5eaec6528ae72fca\", \"gwp_asan_unittests\": \"93cac1d852cbcaa6e4223d3961c80836796dc6d3\", \"headless_browsertests\": \"1fd39b88c0dc9ae645b76f65bb6edea6846126ca\", \"headless_unittests\": \"8833f67250b7f8a001debaa27d3a3f7e103747a8\", \"install_static_unittests\": \"9438137441b9277db93d9e173a68dd473eb18a0a\", \"installer_util_unittests\": \"7e5d412a8cb121c19b5214c012c6c700a10375bb\", \"interactive_ui_tests\": \"feeb70419a5164c3702fa840f8c215ee4ad36ba8\", \"ipc_tests\": \"81d059792b909665c1bee45a1fe7267026dcf87c\", \"jingle_unittests\": \"b7eef6c86607f10c7d94fbc4a26956992fb3f2be\", \"latency_unittests\": \"3922f1bc775be43c7e249dea74b28042d88a40ac\", \"libjingle_xmpp_unittests\": \"855ecd4d8fbe212ec0aa2618e308d5a26a3bdf95\", \"liburlpattern_unittests\": \"21de48be7e51314cd70b29136416a837f3610650\", \"media_blink_unittests\": \"60f3e08248d897732d38ef2ba759ba5fd1ac0214\", \"media_unittests\": \"1ed5f290de225d2d3108b5e18155bb137a76cf96\", \"message_center_unittests\": \"ff3743d96c9a0035a552490f2802f33fcc85a197\", \"midi_unittests\": \"bd5a85b3ce8a1038d4de01df2e69d2574b15ec17\", \"mojo_core_unittests\": \"ae18bc36b3563c5abe80832c2005c84c63263c4f\", \"mojo_unittests\": \"5d342bd34c474a26fb15ea1ab66998359c6b8ced\", \"nacl_loader_unittests\": \"ffe51053061f08ad630993d25527e0c678f9ffb0\", \"native_theme_unittests\": \"d76b2603623f68ec83a39a3129c303cb92260d23\", \"net_unittests\": \"7b6897edd6ef6e2ad7df8852194c8a3ed01080d7\", \"notification_helper_unittests\": \"cc4b184dbac15b84e9579cebf7cee7c581363691\", \"pdf_unittests\": \"eb124f8b8ad43c22e95d123e9e8948830e387ea2\", \"perfetto_unittests\": \"e56fdd95c28e1e1de8b96ec3f09d5e95dee8822c\", \"ppapi_unittests\": \"258d647e0db3c578479ead0883b0818ee0fe1c2a\", \"printing_unittests\": \"3bbb858f0e1a5e37ffa9b212fc9e61abebf59c72\", \"remoting_unittests\": \"74a98580826f1ce24a5cc58ec8fb62c7cc32d600\", \"sbox_integration_tests\": \"d2fa8db8b4740eec10235bc7985ec56d3179986c\", \"sbox_unittests\": \"69a82b0364eb1dcb6957ef4e40f6d71b823ae847\", \"sbox_validation_tests\": \"9231cd0b5cc442d07029ac72db9f5a466ba4febf\", \"service_manager_unittests\": \"6d0a84d77e18f1d77a29e94c1d0a52a99b8b6a11\", \"services_unittests\": \"ebe159d8ffd26291e0538ffb77acdf1ef771825b\", \"setup_unittests\": \"c9d277dcd32dec7ec7f587a974d2e2130b7f06c2\", \"shell_dialogs_unittests\": \"5b77b8854d74c20286293508503ff1fda714150d\", \"skia_unittests\": \"ede3368563d6b7002ef74a717593f4e4523e87ea\", \"snapshot_unittests\": \"4a9abef41a1189c8decb9e46d6f3f4f7aeae44b8\", \"sql_unittests\": \"96e9c602bb664c98bec84967bb23e9c28806b91b\", \"storage_unittests\": \"b9e438a6cf1d0ba298892beca3ce17b54a4e28de\", \"sync_integration_tests\": \"d6d43502f87d14c8a62241c7ad174e342fc027d8\", \"traffic_annotation_auditor_unittests\": \"aa152cecf2a15987662c6627d857dea0fa112d0e\", \"ui_base_unittests\": \"d8a04c2cde7cb89a1b1aca7e2696fa6c6d191218\", \"ui_touch_selection_unittests\": \"66e48975d67769b8a75256be7eef4a16921a8c08\", \"unit_tests\": \"7a56257ed7dc28e2f210e7b1843280a7b4590735\", \"updater_tests\": \"c26bea058660a4ebc4b285d8d50406e030e8aed2\", \"url_unittests\": \"79bfaf70d0f060f3f9d1db725434db9ba5fdde75\", \"views_unittests\": \"3c284dddd92278c6074883066c5490d639c5a2da\", \"viz_unittests\": \"58a6cc9cb65609b291cb360b9988dac527e23917\", \"vr_common_unittests\": \"32b07b085c64e3f35295ea37e70a0ee464dde721\", \"vr_pixeltests\": \"2e99c8a1df1a3ce2fa3ee03496440f13356c328f\", \"weblayer_browsertests\": \"f64b2b8cd8f06581f5cbb0fe73378442fcf2242c\", \"weblayer_unittests\": \"2e9b4eda66f7674c48def63739c4aa0147bcc64c\", \"wm_unittests\": \"ee0b542e5ec9fa7809412cc7e535e92f81d20321\", \"wtf_unittests\": \"8b40c667938045b1009e7adb8b06b7eb2681b4fa\", \"zlib_unittests\": \"69307228fd0317f2dd0ffa2574e71f6e04b426a1\", \"zucchini_unittests\": \"2f7383a3755fc337882ae2fa8d2444c53c8a171b\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-6-7lfd\"], \"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\": [\"5640-d0eb544\"], \"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": "1607185866253281",
"status": "COMPLETED",
"status_changed_ts": "1607189313880275",
"tags": [
"build_address:luci.chromium.ci/win-asan/16045",
"builder:win-asan",
"buildset:commit/git/f1a8be8ac2883a562b2b3a2f8522f0ba962146b4",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f1a8be8ac2883a562b2b3a2f8522f0ba962146b4",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027167255130842128",
"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/8861737192885009920/+/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:504c8f9e9579c410",
"user_agent:luci-scheduler"
],
"updated_ts": "1607189313885988",
"url": "https://ci.chromium.org/b/8861737192885009920",
"utcnow_ts": "1607189315041636"
}
}
[17:28:35.067] Invocation finished in 57m33.984856562s with status SUCCEEDED