[11:29:04.885] New invocation is queued and will start shortly
[11:29:06.455] Starting the invocation (attempt 1)
[11:29:06.499] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:29:06.499] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:29:06.499] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9026733266533319424",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"1a09ac4c2b634e1bdc9ae6420decc4036203ebbd\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9026733266533319424",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/1a09ac4c2b634e1bdc9ae6420decc4036203ebbd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1a09ac4c2b634e1bdc9ae6420decc4036203ebbd",
"gitiles_ref:refs/heads/master"
]
}
[11:29:07.358] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1607599746594080",
"id": "8861303204073375552",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1a09ac4c2b634e1bdc9ae6420decc4036203ebbd\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1607599747011149",
"tags": [
"build_address:luci.chromium.ci/win-asan/16138",
"builder:win-asan",
"buildset:commit/git/1a09ac4c2b634e1bdc9ae6420decc4036203ebbd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1a09ac4c2b634e1bdc9ae6420decc4036203ebbd",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026733266533319424",
"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/8861303204073375552/+/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": "1607599747011296",
"url": "https://ci.chromium.org/b/8861303204073375552",
"utcnow_ts": "1607599747351156"
}
}
[11:29:07.358] Task URL: https://ci.chromium.org/b/8861303204073375552
[11:29:07.359] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:2:0) after 1m0s
[11:29:10.150] Received PubSub notification, asking Buildbucket for the build status
[11:29:10.263] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:07.361] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:2:0)
[11:30:07.361] Timer tick, asking Buildbucket for the build status
[11:30:07.475] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:07.475] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:4:0) after 1m0s
[11:31:07.533] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:4:0)
[11:31:07.533] Timer tick, asking Buildbucket for the build status
[11:31:07.644] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:07.644] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:5:0) after 1m0s
[11:32:07.716] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:5:0)
[11:32:07.716] Timer tick, asking Buildbucket for the build status
[11:32:07.886] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:07.886] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:6:0) after 1m0s
[11:33:07.909] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:6:0)
[11:33:07.909] Timer tick, asking Buildbucket for the build status
[11:33:08.227] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:08.227] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:7:0) after 1m0s
[11:34:08.242] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:7:0)
[11:34:08.242] Timer tick, asking Buildbucket for the build status
[11:34:08.599] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:08.599] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:8:0) after 1m0s
[11:35:08.635] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:8:0)
[11:35:08.635] Timer tick, asking Buildbucket for the build status
[11:35:08.708] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:08.708] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:9:0) after 1m0s
[11:36:08.749] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:9:0)
[11:36:08.749] Timer tick, asking Buildbucket for the build status
[11:36:08.819] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:08.819] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:10:0) after 1m0s
[11:37:08.845] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:10:0)
[11:37:08.845] Timer tick, asking Buildbucket for the build status
[11:37:08.908] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:08.908] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:11:0) after 1m0s
[11:38:09.052] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:11:0)
[11:38:09.052] Timer tick, asking Buildbucket for the build status
[11:38:09.150] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:09.150] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:12:0) after 1m0s
[11:39:09.215] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:12:0)
[11:39:09.215] Timer tick, asking Buildbucket for the build status
[11:39:09.323] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:09.323] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:13:0) after 1m0s
[11:40:09.326] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:13:0)
[11:40:09.326] Timer tick, asking Buildbucket for the build status
[11:40:09.715] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:09.715] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:14:0) after 1m0s
[11:41:09.765] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:14:0)
[11:41:09.765] Timer tick, asking Buildbucket for the build status
[11:41:09.871] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:09.871] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:15:0) after 1m0s
[11:42:09.898] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:15:0)
[11:42:09.898] Timer tick, asking Buildbucket for the build status
[11:42:10.111] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:10.111] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:16:0) after 1m0s
[11:43:10.090] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:16:0)
[11:43:10.090] Timer tick, asking Buildbucket for the build status
[11:43:10.194] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:10.194] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:17:0) after 1m0s
[11:44:10.217] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:17:0)
[11:44:10.217] Timer tick, asking Buildbucket for the build status
[11:44:10.365] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:10.365] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:18:0) after 1m0s
[11:45:10.389] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:18:0)
[11:45:10.389] Timer tick, asking Buildbucket for the build status
[11:45:10.554] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:10.554] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:19:0) after 1m0s
[11:46:10.613] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:19:0)
[11:46:10.613] Timer tick, asking Buildbucket for the build status
[11:46:10.752] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:10.752] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:20:0) after 1m0s
[11:47:10.789] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:20:0)
[11:47:10.789] Timer tick, asking Buildbucket for the build status
[11:47:11.201] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:11.201] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:21:0) after 1m0s
[11:48:11.249] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:21:0)
[11:48:11.249] Timer tick, asking Buildbucket for the build status
[11:48:11.844] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:11.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:22:0) after 1m0s
[11:49:11.900] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:22:0)
[11:49:11.900] Timer tick, asking Buildbucket for the build status
[11:49:12.452] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:12.452] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:23:0) after 1m0s
[11:50:12.508] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:23:0)
[11:50:12.508] Timer tick, asking Buildbucket for the build status
[11:50:12.628] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:12.628] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:24:0) after 1m0s
[11:51:12.674] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:24:0)
[11:51:12.674] Timer tick, asking Buildbucket for the build status
[11:51:12.864] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:12.864] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:25:0) after 1m0s
[11:52:12.886] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:25:0)
[11:52:12.886] Timer tick, asking Buildbucket for the build status
[11:52:13.023] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:13.023] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:26:0) after 1m0s
[11:53:13.075] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:26:0)
[11:53:13.075] Timer tick, asking Buildbucket for the build status
[11:53:15.765] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:15.765] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:27:0) after 1m0s
[11:54:15.801] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:27:0)
[11:54:15.801] Timer tick, asking Buildbucket for the build status
[11:54:15.982] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:15.982] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:28:0) after 1m0s
[11:55:15.996] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:28:0)
[11:55:15.996] Timer tick, asking Buildbucket for the build status
[11:55:16.368] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:16.368] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:29:0) after 1m0s
[11:56:16.423] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:29:0)
[11:56:16.423] Timer tick, asking Buildbucket for the build status
[11:56:16.739] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:16.739] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:30:0) after 1m0s
[11:57:17.503] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:30:0)
[11:57:17.503] Timer tick, asking Buildbucket for the build status
[11:57:17.721] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:17.721] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:31:0) after 1m0s
[11:58:17.829] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:31:0)
[11:58:17.829] Timer tick, asking Buildbucket for the build status
[11:58:18.116] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:18.116] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:32:0) after 1m0s
[11:59:18.911] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:32:0)
[11:59:18.912] Timer tick, asking Buildbucket for the build status
[11:59:19.162] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:19.162] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:33:0) after 1m0s
[12:00:19.194] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:33:0)
[12:00:19.194] Timer tick, asking Buildbucket for the build status
[12:00:19.729] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:19.729] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:34:0) after 1m0s
[12:01:21.529] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:34:0)
[12:01:21.529] Timer tick, asking Buildbucket for the build status
[12:01:21.645] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:21.645] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:35:0) after 1m0s
[12:02:21.968] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:35:0)
[12:02:21.968] Timer tick, asking Buildbucket for the build status
[12:02:22.063] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:22.063] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:36:0) after 1m0s
[12:03:22.482] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:36:0)
[12:03:22.482] Timer tick, asking Buildbucket for the build status
[12:03:22.633] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:22.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:37:0) after 1m0s
[12:04:22.812] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:37:0)
[12:04:22.812] Timer tick, asking Buildbucket for the build status
[12:04:22.944] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:22.944] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:38:0) after 1m0s
[12:05:23.084] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:38:0)
[12:05:23.084] Timer tick, asking Buildbucket for the build status
[12:05:23.168] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:23.168] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:39:0) after 1m0s
[12:06:23.407] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:39:0)
[12:06:23.407] Timer tick, asking Buildbucket for the build status
[12:06:23.474] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:23.474] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:40:0) after 1m0s
[12:07:23.580] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:40:0)
[12:07:23.580] Timer tick, asking Buildbucket for the build status
[12:07:23.724] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:23.724] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:41:0) after 1m0s
[12:08:24.688] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:41:0)
[12:08:24.688] Timer tick, asking Buildbucket for the build status
[12:08:24.811] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:24.811] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:42:0) after 1m0s
[12:09:25.169] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:42:0)
[12:09:25.169] Timer tick, asking Buildbucket for the build status
[12:09:25.303] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:25.303] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:43:0) after 1m0s
[12:10:25.369] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:43:0)
[12:10:25.369] Timer tick, asking Buildbucket for the build status
[12:10:25.483] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:25.483] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:44:0) after 1m0s
[12:11:25.702] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:44:0)
[12:11:25.702] Timer tick, asking Buildbucket for the build status
[12:11:25.839] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:25.839] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:45:0) after 1m0s
[12:12:26.086] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:45:0)
[12:12:26.086] Timer tick, asking Buildbucket for the build status
[12:12:26.209] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:26.209] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:46:0) after 1m0s
[12:13:26.369] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:46:0)
[12:13:26.369] Timer tick, asking Buildbucket for the build status
[12:13:26.625] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:26.625] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:47:0) after 1m0s
[12:14:26.897] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:47:0)
[12:14:26.897] Timer tick, asking Buildbucket for the build status
[12:14:27.324] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:27.324] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:48:0) after 1m0s
[12:15:27.344] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:48:0)
[12:15:27.344] Timer tick, asking Buildbucket for the build status
[12:15:27.506] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:27.506] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:49:0) after 1m0s
[12:16:27.595] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:49:0)
[12:16:27.595] Timer tick, asking Buildbucket for the build status
[12:16:27.806] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:27.806] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:50:0) after 1m0s
[12:17:27.886] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:50:0)
[12:17:27.886] Timer tick, asking Buildbucket for the build status
[12:17:28.159] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:28.159] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:51:0) after 1m0s
[12:18:28.286] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:51:0)
[12:18:28.286] Timer tick, asking Buildbucket for the build status
[12:18:28.384] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:28.385] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:52:0) after 1m0s
[12:19:28.540] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:52:0)
[12:19:28.540] Timer tick, asking Buildbucket for the build status
[12:19:28.677] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:28.677] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:53:0) after 1m0s
[12:20:28.769] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:53:0)
[12:20:28.769] Timer tick, asking Buildbucket for the build status
[12:20:29.179] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:29.180] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:54:0) after 1m0s
[12:21:29.223] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:54:0)
[12:21:29.223] Timer tick, asking Buildbucket for the build status
[12:21:29.691] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:29.691] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:55:0) after 1m0s
[12:22:29.756] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:55:0)
[12:22:29.756] Timer tick, asking Buildbucket for the build status
[12:22:29.834] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:29.834] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:56:0) after 1m0s
[12:23:29.847] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:56:0)
[12:23:29.847] Timer tick, asking Buildbucket for the build status
[12:23:30.045] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:30.045] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:57:0) after 1m0s
[12:24:30.066] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:57:0)
[12:24:30.066] Timer tick, asking Buildbucket for the build status
[12:24:30.238] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:30.238] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:58:0) after 1m0s
[12:25:30.332] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:58:0)
[12:25:30.332] Timer tick, asking Buildbucket for the build status
[12:25:30.775] Build 8861303204073375552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:30.775] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026733266533319424:59:0) after 1m0s
[12:26:28.282] Received PubSub notification, asking Buildbucket for the build status
[12:26:28.355] Build 8861303204073375552: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[12:26:28.355] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1607603185303085",
"created_by": "project:chromium",
"created_ts": "1607599746594080",
"id": "8861303204073375552",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1a09ac4c2b634e1bdc9ae6420decc4036203ebbd\"}}",
"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}, \"$kitchen/crbug_1150511_keys_with_int_value\": [\"$build/goma.jobs\", \"$recipe_engine/buildbucket.build.infra.swarming.priority\", \"$recipe_engine/buildbucket.build.number\", \"buildnumber\"], \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"1a09ac4c2b634e1bdc9ae6420decc4036203ebbd\", \"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-3vmi\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16138, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"067a12f059511ead6e91ab9e91af1d180ad9c144\", \"got_dawn_revision\": \"29fd62989338a0d5812bc654a3afee6dc684bfc4\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"1a09ac4c2b634e1bdc9ae6420decc4036203ebbd\", \"got_revision_cp\": \"refs/heads/master@{#835618}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"d94a77b304f4fcb503ea037be3e5d7f2a371c4b6\", \"got_v8_revision\": \"7e546be6b2f61eb429d51abb78e973f7f623f62f\", \"got_v8_revision_cp\": \"refs/heads/8.9.126@{#1}\", \"got_webrtc_revision\": \"d7808f1c464a07c8f1e2f97ec7ee92fda998d590\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32804}\", \"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\": \"1a09ac4c2b634e1bdc9ae6420decc4036203ebbd\", \"swarm_hashes_refs/heads/master(at){#835618}_without_patch\": {\"absl_hardening_tests\": \"10cb0d9cd3a969fb22162fa7a843d5a1b4368278\", \"accessibility_unittests\": \"95cd059d24d876a7a1c08bb4e9b0dfe03bfc34c8\", \"angle_unittests\": \"f4507280c758c28c0a86f3147030a800163d4328\", \"app_shell_unittests\": \"a05456d671496d16bbe255a673047bdc8c720eff\", \"aura_unittests\": \"0da0f10dc99da2532751ebff40d1c6dc49a2dabd\", \"base_unittests\": \"1e7826cd260f063acbc96d1bdd3e3ec51f660567\", \"base_util_unittests\": \"75939606105ac499ebc7ce29068385ef0acb21dc\", \"blink_common_unittests\": \"662b61e75ec608c9f07d1d5ada3d9b711f4cdf86\", \"blink_fuzzer_unittests\": \"3027eb5896a15a97572e1314259bc41d5dc5907a\", \"blink_heap_unittests\": \"54991f53e642ef97e604f98ebe8d2803e9dc042e\", \"blink_platform_unittests\": \"889e91037b82a27fa54aa1adc029a68b1f89653b\", \"blink_unittests\": \"648d28abab07b83cbd6635914c91feec883b3f70\", \"boringssl_crypto_tests\": \"ab1a1e3e7f334c5b1bfbde35e2067eecfc7a7bbd\", \"boringssl_ssl_tests\": \"764ba4dc3929ab9d7905c283024963e9ee0fc975\", \"browser_switcher_bho_unittests\": \"f7170bdb9a823c80b0d3025e816a41026a9ac21d\", \"browser_tests\": \"fa22b85a44c800fff6385c8dd70c68b64c515313\", \"capture_unittests\": \"b6829ffa2185d1a43c24b2e4fbd5037e219a9593\", \"cast_unittests\": \"9917e7e0f9f12c27b10b9f7b7ae2a5ab96ed2de0\", \"cc_unittests\": \"c7627cc3bebb4b91d5c37e881f12c6be4726fca2\", \"chrome_app_unittests\": \"501fb0b7ec3211e3f0b0145eabdf6e3eee76476d\", \"chrome_cleaner_unittests\": \"80f048e76c3379259f62f9296fd048a03d9b786e\", \"chrome_elf_unittests\": \"efee7a2912841c8966dd4e4c9dd6706eca34f8de\", \"chromedriver_unittests\": \"1fcbfe0f23fa5791532da4fcfd6f508b8e50fd5f\", \"components_browsertests\": \"256c0c67f47b0caa8b728a6fa28b8aff9cf36da5\", \"components_unittests\": \"5e9c237de29748989c85992a792bf960912937ec\", \"compositor_unittests\": \"245e10a1b5b01a08d47b161e593166a0c2693c82\", \"content_browsertests\": \"eca02a6f9d97cfa6fd205f645695c2c9d0fc9358\", \"content_unittests\": \"520737f72ccfeb940dd4f88be7db70b2d4be1df2\", \"courgette_unittests\": \"5ef7f8571d89f2749c8640d63c0748b0d03d1fbd\", \"crashpad_tests\": \"19f4fa8f74370bd938cf7620c86469faaeb59397\", \"cronet_tests\": \"71648583f45a364f471556e594b5c905931112bb\", \"cronet_unittests\": \"2fcdad64e6aa4fbb15dfcf2cda10f7f995568738\", \"crypto_unittests\": \"b3659c7f9329ae23b26e54a8a4d7ef9cd200b3d4\", \"delayloads_unittests\": \"0f178fa66913c2b74981e8ccfefba40019339845\", \"device_unittests\": \"257b00186dc8166a2761b0713572d864153280ca\", \"display_unittests\": \"80bac625c47ec5aa06f3fbb630fa5517c142d34b\", \"elevation_service_unittests\": \"01a4f59556c3e8cf0f5e251b92beedcc3f9fff78\", \"events_unittests\": \"e2b1269fbd4f258a5715f0bbd17f412e397abcfd\", \"extensions_browsertests\": \"84fef465f3d0055dbd0120a92c854c343355539d\", \"extensions_unittests\": \"4e04b63f204eae719c87fab9da498e4294057a27\", \"filesystem_service_unittests\": \"a60d082b2002d474dc9651469379c5672e640964\", \"gcm_unit_tests\": \"b1a322920e01f35a8f909b0d674b5d5631ed36b1\", \"gcp_unittests\": \"13985ec77f5b8eb6d5bfbd90497c3ed7b127ec25\", \"gfx_unittests\": \"3589926bed3c098b647b835608b5a7b54e40b183\", \"gin_unittests\": \"700e397f4826008a242dd5dbe5714e5fb9d03751\", \"google_apis_unittests\": \"34f62eaddb563f5ad8080ee81f22afad55d6774d\", \"gpu_unittests\": \"990ff1392ea0aaa53a26fed8ba5327d339a3e943\", \"gwp_asan_unittests\": \"04c44bf29df0cab4f8f68cee76cb0e556084ed7b\", \"headless_browsertests\": \"aa8e3ba233b676e902bd68fb3926024bbccfacfb\", \"headless_unittests\": \"f1a89651d1255a822979d0e2b6d6596d37ebd47e\", \"install_static_unittests\": \"5bf5a03bffbc79652de4829959e003794d5c5261\", \"installer_util_unittests\": \"86f7677c017cb8b037cb6d3e747c6e6ea7b7d143\", \"interactive_ui_tests\": \"5a4a1de65839182905c3d92971d1577572a74eba\", \"ipc_tests\": \"d4727f9bb22edaa6f1fa398a1f0c70fce7430490\", \"jingle_unittests\": \"e2d573618a8ffcd05413db8e8373390db4cfab79\", \"latency_unittests\": \"2c72777ac74803f26adfeaabe196a115d123f8ac\", \"libjingle_xmpp_unittests\": \"fa8c34da6d9fb37e803d160eccbd69d863be6e03\", \"liburlpattern_unittests\": \"64e12104afff5442e7b1593975b3de1e99cf70f6\", \"media_blink_unittests\": \"cba42f0ffd359c992aa42ebb535b2229f6fc59cd\", \"media_unittests\": \"47bfcdb1cc3d62c3f259a0eaf5e5de895c8565c3\", \"message_center_unittests\": \"38a889145fb9eecce543f7cc7e1c51b534ac6fb3\", \"midi_unittests\": \"9e5af0c6e76cb4ed3c60734fdb8766244e667b68\", \"mojo_core_unittests\": \"14c90b1ed1946cd34fa7d9a4be3b9e397dd51198\", \"mojo_unittests\": \"c183a66dda7477d79797ec4225568f2f817bdcef\", \"nacl_loader_unittests\": \"efb7da0f440fd68ed7b21b58cc7d4013de47cc91\", \"native_theme_unittests\": \"5529efdab896babff90075bcc3cd2e300d9f7670\", \"net_unittests\": \"00023b95695cceefcfc777e7be9abc96e3888f97\", \"notification_helper_unittests\": \"e072e5d4aa2fe61c48d281ba76958808ef1e6267\", \"pdf_unittests\": \"2597d202feb57a95eaa2ea7bf9821ff469d6da30\", \"perfetto_unittests\": \"018f5d8142016c2e1183b3934859dc15177da4f3\", \"ppapi_unittests\": \"991d5d218e40a1a883c9574bf98260546ec0ca94\", \"printing_unittests\": \"ba24d97e1db1159561e1222283a4f072de9726ac\", \"remoting_unittests\": \"182dc3dc7b41107343312be37608a11458618eed\", \"sbox_integration_tests\": \"235677d6689da7eef708171065db541920e94221\", \"sbox_unittests\": \"b6ce798064d254f9c5d3cee5505ac214feaf4b0e\", \"sbox_validation_tests\": \"1b72e35e3c8d7d5252f424e896dbb1fc4f70b467\", \"service_manager_unittests\": \"4e4d5b1489e1f32e2017228bc0e4fdee83ba9422\", \"services_unittests\": \"cff1713698cbac5a4b768d14fc17c28fd1660917\", \"setup_unittests\": \"44dd09d41dbf7b6331b68fefb7e07118afd264cc\", \"shell_dialogs_unittests\": \"6b864c64a897be37cad397b832516416898c82ef\", \"skia_unittests\": \"2a000f1cc05031a97adcc37a8a6317cf029e05e3\", \"snapshot_unittests\": \"61b2518bdc144a55d9cf2ad94ad2480726486cbb\", \"sql_unittests\": \"aff24703dd5684be6b5838bba4f75e747b2741b8\", \"storage_unittests\": \"480e05b3d09d254f6a88098af0aea6076122d8d4\", \"sync_integration_tests\": \"93f45d39a8bb2186042958ba714fd83bb90f220c\", \"traffic_annotation_auditor_unittests\": \"fe7e3566bc02a4d9cd56b8ee35e580e969876046\", \"ui_base_unittests\": \"5d21cd2a1ac40d830e290e17ed672a43a508bf4b\", \"ui_touch_selection_unittests\": \"b1bea6370308303a50e6aded119dbaaa73585f32\", \"unit_tests\": \"cf2ae6560d9c8b32ceb54f6c2483bb7400c5336e\", \"updater_tests\": \"ff75cb33d4d17d1103b0dcf33b347f37c3156aa5\", \"url_unittests\": \"d56fdaab6c8b255bbbc700d234650e20a777158d\", \"views_unittests\": \"5541531907c0627b0b9b9e46d00d600288614f75\", \"viz_unittests\": \"29ef379e02a5706c6f95ad6b79d972ec24a2fbf4\", \"vr_common_unittests\": \"9b143a68b110e06a728462d45b0413944317d47b\", \"vr_pixeltests\": \"a4b82d728d18fea67e321d0bd60aba821844e8a5\", \"weblayer_browsertests\": \"f0bc76c7e1978c8e8e8d88c8ceccb9e769ff8667\", \"weblayer_unittests\": \"cf0ed3b92fab5314184fdb06113be0116e437d11\", \"wm_unittests\": \"562e29fb35b6a01741b3beff4377c557867f570b\", \"wtf_unittests\": \"0a95278c4b82ed4f218f9bf53c0c7c16e94ec860\", \"zlib_unittests\": \"524e025f64c2d1fb6720bd29b409750157610dff\", \"zucchini_unittests\": \"c5c4bee6c7ddfad2cc0f3b54703abc7d326eaff9\"}}, \"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-3vmi\"], \"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-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1607599748614985",
"status": "COMPLETED",
"status_changed_ts": "1607603185694337",
"tags": [
"build_address:luci.chromium.ci/win-asan/16138",
"builder:win-asan",
"buildset:commit/git/1a09ac4c2b634e1bdc9ae6420decc4036203ebbd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1a09ac4c2b634e1bdc9ae6420decc4036203ebbd",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026733266533319424",
"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/8861303204073375552/+/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:50653af9f8b5f510",
"user_agent:luci-scheduler"
],
"updated_ts": "1607603185775477",
"url": "https://ci.chromium.org/b/8861303204073375552",
"utcnow_ts": "1607603188344721"
}
}
[12:26:28.355] Invocation finished in 57m23.489797995s with status SUCCEEDED