[06:07:45.039] New invocation is queued and will start shortly
[06:07:46.146] Starting the invocation (attempt 1)
[06:07:46.180] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:07:46.180] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:07:46.180] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9027568855611826864",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"2f1a893e1b651fe99398f36b68b63605c8f0391e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9027568855611826864",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/2f1a893e1b651fe99398f36b68b63605c8f0391e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2f1a893e1b651fe99398f36b68b63605c8f0391e",
"gitiles_ref:refs/heads/master"
]
}
[06:07:47.553] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1606802866253631",
"id": "8862138793673932320",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"2f1a893e1b651fe99398f36b68b63605c8f0391e\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1606802867282372",
"tags": [
"build_address:luci.chromium.ci/win-asan/15961",
"builder:win-asan",
"buildset:commit/git/2f1a893e1b651fe99398f36b68b63605c8f0391e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2f1a893e1b651fe99398f36b68b63605c8f0391e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027568855611826864",
"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/8862138793673932320/+/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": "1606802867282524",
"url": "https://ci.chromium.org/b/8862138793673932320",
"utcnow_ts": "1606802867541682"
}
}
[06:07:47.553] Task URL: https://ci.chromium.org/b/8862138793673932320
[06:07:47.553] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:2:0) after 1m0s
[06:07:48.902] Received PubSub notification, asking Buildbucket for the build status
[06:07:49.010] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:47.585] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:2:0)
[06:08:47.585] Timer tick, asking Buildbucket for the build status
[06:08:47.676] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:47.676] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:4:0) after 1m0s
[06:09:47.713] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:4:0)
[06:09:47.713] Timer tick, asking Buildbucket for the build status
[06:09:47.828] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:47.828] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:5:0) after 1m0s
[06:10:47.809] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:5:0)
[06:10:47.809] Timer tick, asking Buildbucket for the build status
[06:10:47.963] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:47.963] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:6:0) after 1m0s
[06:11:47.980] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:6:0)
[06:11:47.980] Timer tick, asking Buildbucket for the build status
[06:11:48.087] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:48.087] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:7:0) after 1m0s
[06:12:48.113] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:7:0)
[06:12:48.113] Timer tick, asking Buildbucket for the build status
[06:12:48.188] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:48.188] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:8:0) after 1m0s
[06:13:48.215] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:8:0)
[06:13:48.215] Timer tick, asking Buildbucket for the build status
[06:13:48.616] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:48.616] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:9:0) after 1m0s
[06:14:48.675] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:9:0)
[06:14:48.675] Timer tick, asking Buildbucket for the build status
[06:14:48.743] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:48.743] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:10:0) after 1m0s
[06:15:48.772] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:10:0)
[06:15:48.772] Timer tick, asking Buildbucket for the build status
[06:15:48.843] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:48.843] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:11:0) after 1m0s
[06:16:48.902] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:11:0)
[06:16:48.902] Timer tick, asking Buildbucket for the build status
[06:16:48.989] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:48.989] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:12:0) after 1m0s
[06:17:49.042] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:12:0)
[06:17:49.042] Timer tick, asking Buildbucket for the build status
[06:17:49.301] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:49.301] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:13:0) after 1m0s
[06:18:49.374] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:13:0)
[06:18:49.374] Timer tick, asking Buildbucket for the build status
[06:18:49.629] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:49.629] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:14:0) after 1m0s
[06:19:49.689] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:14:0)
[06:19:49.689] Timer tick, asking Buildbucket for the build status
[06:19:49.761] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:49.761] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:15:0) after 1m0s
[06:20:49.786] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:15:0)
[06:20:49.786] Timer tick, asking Buildbucket for the build status
[06:20:49.863] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:49.863] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:16:0) after 1m0s
[06:21:49.919] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:16:0)
[06:21:49.919] Timer tick, asking Buildbucket for the build status
[06:21:50.002] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:50.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:17:0) after 1m0s
[06:22:50.169] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:17:0)
[06:22:50.169] Timer tick, asking Buildbucket for the build status
[06:22:50.282] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:50.282] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:18:0) after 1m0s
[06:23:50.387] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:18:0)
[06:23:50.387] Timer tick, asking Buildbucket for the build status
[06:23:50.469] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:50.469] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:19:0) after 1m0s
[06:24:50.586] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:19:0)
[06:24:50.586] Timer tick, asking Buildbucket for the build status
[06:24:51.342] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:51.342] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:20:0) after 1m0s
[06:25:51.394] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:20:0)
[06:25:51.394] Timer tick, asking Buildbucket for the build status
[06:25:51.823] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:51.823] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:21:0) after 1m0s
[06:26:51.909] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:21:0)
[06:26:51.909] Timer tick, asking Buildbucket for the build status
[06:26:52.019] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:52.019] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:22:0) after 1m0s
[06:27:52.037] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:22:0)
[06:27:52.037] Timer tick, asking Buildbucket for the build status
[06:27:52.266] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:52.266] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:23:0) after 1m0s
[06:28:52.382] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:23:0)
[06:28:52.382] Timer tick, asking Buildbucket for the build status
[06:28:52.573] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:52.573] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:24:0) after 1m0s
[06:29:52.595] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:24:0)
[06:29:52.595] Timer tick, asking Buildbucket for the build status
[06:29:52.733] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:52.733] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:25:0) after 1m0s
[06:30:52.744] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:25:0)
[06:30:52.744] Timer tick, asking Buildbucket for the build status
[06:30:52.874] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:52.874] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:26:0) after 1m0s
[06:31:52.894] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:26:0)
[06:31:52.894] Timer tick, asking Buildbucket for the build status
[06:31:53.405] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:53.405] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:27:0) after 1m0s
[06:32:53.428] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:27:0)
[06:32:53.428] Timer tick, asking Buildbucket for the build status
[06:32:53.684] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:53.684] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:28:0) after 1m0s
[06:33:53.707] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:28:0)
[06:33:53.707] Timer tick, asking Buildbucket for the build status
[06:33:53.972] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:53.972] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:29:0) after 1m0s
[06:34:53.991] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:29:0)
[06:34:53.991] Timer tick, asking Buildbucket for the build status
[06:34:54.148] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:54.148] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:30:0) after 1m0s
[06:35:54.167] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:30:0)
[06:35:54.167] Timer tick, asking Buildbucket for the build status
[06:35:54.573] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:54.573] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:31:0) after 1m0s
[06:36:54.620] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:31:0)
[06:36:54.620] Timer tick, asking Buildbucket for the build status
[06:36:54.928] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:54.928] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:32:0) after 1m0s
[06:37:54.986] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:32:0)
[06:37:54.986] Timer tick, asking Buildbucket for the build status
[06:37:55.642] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:55.642] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:33:0) after 1m0s
[06:38:55.649] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:33:0)
[06:38:55.649] Timer tick, asking Buildbucket for the build status
[06:38:55.829] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:55.829] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:34:0) after 1m0s
[06:39:55.933] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:34:0)
[06:39:55.933] Timer tick, asking Buildbucket for the build status
[06:39:56.015] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:56.015] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:35:0) after 1m0s
[06:40:56.043] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:35:0)
[06:40:56.043] Timer tick, asking Buildbucket for the build status
[06:40:56.562] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:56.562] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:36:0) after 1m0s
[06:41:56.598] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:36:0)
[06:41:56.598] Timer tick, asking Buildbucket for the build status
[06:41:56.688] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:56.688] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:37:0) after 1m0s
[06:42:56.714] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:37:0)
[06:42:56.714] Timer tick, asking Buildbucket for the build status
[06:42:56.864] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:56.864] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:38:0) after 1m0s
[06:43:56.860] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:38:0)
[06:43:56.860] Timer tick, asking Buildbucket for the build status
[06:43:57.017] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:57.017] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:39:0) after 1m0s
[06:44:57.038] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:39:0)
[06:44:57.038] Timer tick, asking Buildbucket for the build status
[06:44:57.165] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:57.165] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:40:0) after 1m0s
[06:45:57.222] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:40:0)
[06:45:57.222] Timer tick, asking Buildbucket for the build status
[06:45:57.972] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:57.972] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:41:0) after 1m0s
[06:46:57.956] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:41:0)
[06:46:57.956] Timer tick, asking Buildbucket for the build status
[06:46:58.045] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:58.045] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:42:0) after 1m0s
[06:47:58.083] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:42:0)
[06:47:58.083] Timer tick, asking Buildbucket for the build status
[06:47:58.213] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:58.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:43:0) after 1m0s
[06:48:58.260] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:43:0)
[06:48:58.260] Timer tick, asking Buildbucket for the build status
[06:48:58.342] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:58.342] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:44:0) after 1m0s
[06:49:58.361] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:44:0)
[06:49:58.361] Timer tick, asking Buildbucket for the build status
[06:49:58.560] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:58.560] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:45:0) after 1m0s
[06:50:58.620] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:45:0)
[06:50:58.620] Timer tick, asking Buildbucket for the build status
[06:50:58.703] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:58.703] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:46:0) after 1m0s
[06:51:58.734] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:46:0)
[06:51:58.734] Timer tick, asking Buildbucket for the build status
[06:51:59.039] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:59.039] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:47:0) after 1m0s
[06:52:59.067] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:47:0)
[06:52:59.067] Timer tick, asking Buildbucket for the build status
[06:52:59.231] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:59.231] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:48:0) after 1m0s
[06:53:59.253] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:48:0)
[06:53:59.253] Timer tick, asking Buildbucket for the build status
[06:54:00.303] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:00.303] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:49:0) after 1m0s
[06:55:00.380] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:49:0)
[06:55:00.380] Timer tick, asking Buildbucket for the build status
[06:55:00.472] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:00.472] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:50:0) after 1m0s
[06:56:00.583] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:50:0)
[06:56:00.583] Timer tick, asking Buildbucket for the build status
[06:56:00.679] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:00.679] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:51:0) after 1m0s
[06:57:00.877] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:51:0)
[06:57:00.877] Timer tick, asking Buildbucket for the build status
[06:57:01.008] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:01.008] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:52:0) after 1m0s
[06:58:01.227] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:52:0)
[06:58:01.227] Timer tick, asking Buildbucket for the build status
[06:58:01.375] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:01.375] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:53:0) after 1m0s
[06:59:01.434] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:53:0)
[06:59:01.434] Timer tick, asking Buildbucket for the build status
[06:59:01.633] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:01.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:54:0) after 1m0s
[07:00:01.727] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:54:0)
[07:00:01.727] Timer tick, asking Buildbucket for the build status
[07:00:02.290] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:02.290] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:55:0) after 1m0s
[07:01:02.432] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:55:0)
[07:01:02.432] Timer tick, asking Buildbucket for the build status
[07:01:02.875] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:02.875] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:56:0) after 1m0s
[07:02:03.118] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:56:0)
[07:02:03.118] Timer tick, asking Buildbucket for the build status
[07:02:03.371] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:03.371] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:57:0) after 1m0s
[07:03:03.398] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:57:0)
[07:03:03.398] Timer tick, asking Buildbucket for the build status
[07:03:03.766] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:03.766] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:58:0) after 1m0s
[07:04:03.817] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:58:0)
[07:04:03.817] Timer tick, asking Buildbucket for the build status
[07:04:03.923] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:03.923] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:59:0) after 1m0s
[07:05:03.985] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:59:0)
[07:05:03.985] Timer tick, asking Buildbucket for the build status
[07:05:04.120] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:04.120] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:60:0) after 1m0s
[07:06:04.157] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:60:0)
[07:06:04.157] Timer tick, asking Buildbucket for the build status
[07:06:04.409] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:04.409] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:61:0) after 1m0s
[07:07:04.505] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:61:0)
[07:07:04.505] Timer tick, asking Buildbucket for the build status
[07:07:04.610] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:04.610] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:62:0) after 1m0s
[07:08:04.636] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:62:0)
[07:08:04.636] Timer tick, asking Buildbucket for the build status
[07:08:04.784] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:04.784] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:63:0) after 1m0s
[07:09:04.839] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:63:0)
[07:09:04.839] Timer tick, asking Buildbucket for the build status
[07:09:04.992] Build 8862138793673932320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:04.992] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027568855611826864:64:0) after 1m0s
[07:10:01.305] Received PubSub notification, asking Buildbucket for the build status
[07:10:01.431] Build 8862138793673932320: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[07:10:01.431] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1606806600459177",
"created_by": "project:chromium",
"created_ts": "1606802866253631",
"id": "8862138793673932320",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"2f1a893e1b651fe99398f36b68b63605c8f0391e\"}}",
"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\": \"2f1a893e1b651fe99398f36b68b63605c8f0391e\", \"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-10-eupo\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15961, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"ff38106d62fca2e36ec2db1cd580b546fd53ae43\", \"got_dawn_revision\": \"a827aa2c29aa41346e95e6ac58a752709beb5977\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"2f1a893e1b651fe99398f36b68b63605c8f0391e\", \"got_revision_cp\": \"refs/heads/master@{#832224}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"1eadcfb3be99c2eca944774a78c4e3c16132958b\", \"got_v8_revision\": \"f1e2bbd80f2e2eacf7e043e18ee55f0b8cf22171\", \"got_v8_revision_cp\": \"refs/heads/8.9.71@{#1}\", \"got_webrtc_revision\": \"bcca3b08a90fccaa6dd8d86f6079f0ca0553ab4a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32715}\", \"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\": \"2f1a893e1b651fe99398f36b68b63605c8f0391e\", \"swarm_hashes_refs/heads/master(at){#832224}_without_patch\": {\"absl_hardening_tests\": \"a46c58e7b26fe663bbb3e2a6410a5ef074e84ccf\", \"accessibility_unittests\": \"bfe874e2b7d5f67f29a0dffbd33d92292fe90f5e\", \"angle_unittests\": \"9fe7452e3f81533a3611b5729763c8b7c208fd1f\", \"app_shell_unittests\": \"442047f273dd7e412ad8a0b0c98aa4d6eb9a21a4\", \"aura_unittests\": \"fad516357b8a75619d7aacfbbf37e8cae3a3449b\", \"base_unittests\": \"a6d04eae8606d99258dcc9ae7546263636b990a7\", \"base_util_unittests\": \"a472347e2c8cc48a19df07ba192f583c3d34acb9\", \"blink_common_unittests\": \"e198e4563837c81621066a19850e8b2b66cdbeec\", \"blink_fuzzer_unittests\": \"431f55e7e1a113b099fc002f01150b26b74816e3\", \"blink_heap_unittests\": \"3649f8bfcee15e5f271ea648b5d8c6cd66efe087\", \"blink_platform_unittests\": \"d4964f4a5b5e1d08d1b33d0876e0a4fc0f91c264\", \"blink_unittests\": \"21a208d8d7609758c48ba2a0ac6b6a234e2a4eef\", \"boringssl_crypto_tests\": \"10dc9f721d0a41d098a266dff9f1196da6bfbe40\", \"boringssl_ssl_tests\": \"e903ed21b1df04649ee90cde37d4d360e41b2de8\", \"browser_switcher_bho_unittests\": \"3bb341c7d6c92978a824d8d7bee0e4023d7aaf2b\", \"browser_tests\": \"a5b38a5ab097bad703aa9bc6e7b9414a65620b37\", \"capture_unittests\": \"670ff10e2effda69efad2607b2b90a5e53f2153e\", \"cast_unittests\": \"580c6f74fe0b1277e550077d825483d8e1075ba6\", \"cc_unittests\": \"3db02fac3ca4f43d02bf4fdc329ca0f234133550\", \"chrome_app_unittests\": \"02744005e0b485f87c7ae725301200df1913280e\", \"chrome_cleaner_unittests\": \"31768a653d908bef6f2ffcf0b7ddb8bc41c24c52\", \"chrome_elf_unittests\": \"6c5276299213f66061e3a8ca9951bdd82e242286\", \"chromedriver_unittests\": \"17cbd1d64d817caf56bdf77122758df6b922b9cb\", \"components_browsertests\": \"66a3b2a66652ab4261d327a5d5040b61d36164eb\", \"components_unittests\": \"2a3c8b81186dd5a8c84c84c6bbc5fa0f418cd5bf\", \"compositor_unittests\": \"25ca2391cfb99372e08ed8aafd3a4f4c53df9687\", \"content_browsertests\": \"4dafe1acf7fe5772d5e8d9033c9f699349811471\", \"content_unittests\": \"721c7378b62061840cc17c0dff3828b43de33794\", \"courgette_unittests\": \"1e170551d86feb2e0efad232e058852d9efe48f1\", \"crashpad_tests\": \"213c52b6839941607cf9942d69583e0d8e185867\", \"cronet_tests\": \"cb7118a4c8e5bb734e25beff887ff45531928031\", \"cronet_unittests\": \"7145b99bdb9eeabf90b60a14e968f62fd5c84dbf\", \"crypto_unittests\": \"47b69d2f240f9c4368214f5f5fe0d3f8323832c3\", \"delayloads_unittests\": \"8feaf95179986dae6612ebb547c4a29a3b4a6b27\", \"device_unittests\": \"203d086d2c8d5afd9813aa7a935179507ee823ed\", \"display_unittests\": \"0622fa372eaef56b4143185d54976c6478142ca3\", \"elevation_service_unittests\": \"809c8fbf3d8306fdcfc7e3f011e6464e09746b48\", \"events_unittests\": \"38cf1877510895bb94f51555ce7be0b42f19a122\", \"extensions_browsertests\": \"52aab18ed0b0b160135ff67aab1bd78d7f641cff\", \"extensions_unittests\": \"8ef2ba9530d3ff914c886a394e91d3ad46eab1b0\", \"filesystem_service_unittests\": \"7f91c09b01101582d1dadcbc7660577924b7d752\", \"gcm_unit_tests\": \"09542b60c1cf6827ef38c579d420a6546ce7d69b\", \"gcp_unittests\": \"2b3505b120a701c73427a571f97f80425f99c027\", \"gfx_unittests\": \"e438fdabb43eaa8e4fbb3f77677b146488eff914\", \"gin_unittests\": \"6d370391af84fe31aefb1dc03731fba2ad203e80\", \"google_apis_unittests\": \"50c94948f9c23bb0e86a836ba0288d525a951ab3\", \"gpu_unittests\": \"1a33c1bdb5e9e6c199720c2aa7f465375f3d9e78\", \"gwp_asan_unittests\": \"56d0b45c50fa0d46d9f677c5f25b5c0755b90dfd\", \"headless_browsertests\": \"c292de04fc08a3a3acd24139be9398514bb11b32\", \"headless_unittests\": \"26553e07e78a24bd93176c6d20a30d1c49bd5f12\", \"install_static_unittests\": \"d6a99b81f9b3405d5dcdf976fb486fe1d1343a6b\", \"installer_util_unittests\": \"45c072b5bdd4426d2a95c0717c7f1a885c7b43bc\", \"interactive_ui_tests\": \"05925540fbc9d81d353721030303160eea1a60b2\", \"ipc_tests\": \"514df5bcb673e8cd2d8f073f664d2a38a484f8e3\", \"jingle_unittests\": \"5254585db7ba3cd95aada8979a0712c7fbb4ffac\", \"latency_unittests\": \"006d68c652c2b73169c8583e36769d37fe42581f\", \"libjingle_xmpp_unittests\": \"5a1b795a174b763f955002b4c0b365aca078abcd\", \"liburlpattern_unittests\": \"02391d14c3f1e446c4498413c74793b28a8adeeb\", \"media_blink_unittests\": \"d5df44b1574b137bc64e2b867d5358cea5e971aa\", \"media_unittests\": \"0d30cd9aec31a74ecb8fb598c200be68293d8659\", \"message_center_unittests\": \"1463e09f519cf42996287046889c36301518540b\", \"midi_unittests\": \"38ba41d9d087297b41e333ee4107cdf89d69bca4\", \"mojo_core_unittests\": \"7ac3ccc5af263cc0c4c87af78c5a40e4c55233a4\", \"mojo_unittests\": \"c6dedd05f1d4d980622e0b610fdb8c21a6d62278\", \"nacl_loader_unittests\": \"87960ec7ad31eca4f50159b33699953c916472de\", \"native_theme_unittests\": \"30ec59c3c59fbe8814b3112193e66d158a43d5e7\", \"net_unittests\": \"7a2a6c92b15d97c9cae3f3cb8a42992422724e14\", \"notification_helper_unittests\": \"68bc49cf307f180224783ae5e2d630c49efce5e2\", \"pdf_unittests\": \"37673f09fa687ae06096989cf83b404b34de50ac\", \"perfetto_unittests\": \"0df840b2d7a5de929fca4475c830c19759d23cef\", \"ppapi_unittests\": \"8bd4b69af168f4b60f4e912ad5236ca55da42ae8\", \"printing_unittests\": \"414148b167ed05466b099ea41f1351d7e5d1a66b\", \"remoting_unittests\": \"60e20729946a861f7adf8adee4e306cb2f804283\", \"sbox_integration_tests\": \"737cef9f4aad291bb979be73acb9e91580582482\", \"sbox_unittests\": \"63f8ae59cccd65f386503fed8af7793a37ab6dab\", \"sbox_validation_tests\": \"15b5eda17dc7070d2378cdea2c806da31e0e11c9\", \"service_manager_unittests\": \"e4c2ef73bafe48ad3dc99835f521ee46e50772ac\", \"services_unittests\": \"5162c37dc49cd2d6a6f3fc32d3cff843bcf3b4e5\", \"setup_unittests\": \"a6990c84481480e7868111f68194cb665b5eb1eb\", \"shell_dialogs_unittests\": \"3b812e7997d9633074664d3d3c63d2205401345a\", \"skia_unittests\": \"eb7dfe6a30d0bf4ed282cbb395ad343f6618b10c\", \"snapshot_unittests\": \"dca4e96ce06a99ef52d04c25bd044c0f6aa77b73\", \"sql_unittests\": \"c1fa1e5246c440472ffaf34f3ee04b60f1571672\", \"storage_unittests\": \"97425433e0b8f62f5f4041a941248a1f408a2990\", \"sync_integration_tests\": \"c1f4739a463ee855e11064d3530f142f0d4ceccf\", \"traffic_annotation_auditor_unittests\": \"ac030209f039bb8bf336dbe4879e0ebd1132a681\", \"ui_base_unittests\": \"3c4137ae00ce525c2b382a75aa69466af480b393\", \"ui_touch_selection_unittests\": \"927385381cb2169a05bfe0e4c5516202ca29cb88\", \"unit_tests\": \"6e8c6c0d9b448724a7459a1b062c81ddbe80197b\", \"updater_tests\": \"b6f3292e2b52d2bd6286db41b963f937d9a4d251\", \"url_unittests\": \"9ef7a8a8a0f11ca49cfcace5f07578d9ee1b7eb0\", \"views_unittests\": \"867346d44bf8b8ac888bafd8c95ff0c7d4094ec8\", \"viz_unittests\": \"3188c97879c2614c42bcee45a1e7986354cecf98\", \"vr_common_unittests\": \"c3691be2d17ffc0302fb904c2a1deb15b4f99f7d\", \"vr_pixeltests\": \"597355454507ff771114d6916d13c7ee7919ab9e\", \"weblayer_browsertests\": \"d9f755cdcc602f1d93b236c001aa02171606b6dd\", \"weblayer_unittests\": \"b8ee9b7604331c5aab343423cfc9f092c98ab5bd\", \"wm_unittests\": \"0c5554700a5405fa98044a4df1dc9795b48f0f06\", \"wtf_unittests\": \"f76667ef5148527bfdffd043173418e8bb8de619\", \"zlib_unittests\": \"b453f0ff79bfc1c1b58daedfca05ea854c768f97\", \"zucchini_unittests\": \"ff097ad93a53e093c7e6e6b1e71317a8309c3648\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_91b2ef8341586e76bd64fa3093e74ce43aef344c2e2134fb3b046b47c3d4bcdb_v2\", \"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-10-eupo\"], \"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": "1606802868139486",
"status": "COMPLETED",
"status_changed_ts": "1606806600932395",
"tags": [
"build_address:luci.chromium.ci/win-asan/15961",
"builder:win-asan",
"buildset:commit/git/2f1a893e1b651fe99398f36b68b63605c8f0391e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2f1a893e1b651fe99398f36b68b63605c8f0391e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027568855611826864",
"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/8862138793673932320/+/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:5035bb8d867a9e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1606806600944857",
"url": "https://ci.chromium.org/b/8862138793673932320",
"utcnow_ts": "1606806601413270"
}
}
[07:10:01.431] Invocation finished in 1h2m16.408897416s with status SUCCEEDED