[07:03:19.208] New invocation is queued and will start shortly
[07:03:20.432] Starting the invocation (attempt 1)
[07:03:20.474] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:03:20.474] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:03:20.474] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9027293568591638944",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"f3d6ac247840332df6a7bab45cf614b913f28d24\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9027293568591638944",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/f3d6ac247840332df6a7bab45cf614b913f28d24",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f3d6ac247840332df6a7bab45cf614b913f28d24",
"gitiles_ref:refs/heads/master"
]
}
[07:03:21.486] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1607065400595524",
"id": "8861863506464134224",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f3d6ac247840332df6a7bab45cf614b913f28d24\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1607065401363743",
"tags": [
"build_address:luci.chromium.ci/win-asan/16019",
"builder:win-asan",
"buildset:commit/git/f3d6ac247840332df6a7bab45cf614b913f28d24",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f3d6ac247840332df6a7bab45cf614b913f28d24",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027293568591638944",
"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/8861863506464134224/+/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": "1607065401363920",
"url": "https://ci.chromium.org/b/8861863506464134224",
"utcnow_ts": "1607065401477745"
}
}
[07:03:21.486] Task URL: https://ci.chromium.org/b/8861863506464134224
[07:03:21.486] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:2:0) after 1m0s
[07:03:25.909] Received PubSub notification, asking Buildbucket for the build status
[07:03:26.199] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:21.588] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:2:0)
[07:04:21.588] Timer tick, asking Buildbucket for the build status
[07:04:21.662] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:21.662] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:4:0) after 1m0s
[07:05:21.746] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:4:0)
[07:05:21.746] Timer tick, asking Buildbucket for the build status
[07:05:21.872] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:21.872] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:5:0) after 1m0s
[07:06:21.987] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:5:0)
[07:06:21.987] Timer tick, asking Buildbucket for the build status
[07:06:22.106] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:22.106] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:6:0) after 1m0s
[07:07:22.166] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:6:0)
[07:07:22.167] Timer tick, asking Buildbucket for the build status
[07:07:22.308] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:22.308] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:7:0) after 1m0s
[07:08:22.391] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:7:0)
[07:08:22.391] Timer tick, asking Buildbucket for the build status
[07:08:22.750] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:22.750] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:8:0) after 1m0s
[07:09:22.787] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:8:0)
[07:09:22.787] Timer tick, asking Buildbucket for the build status
[07:09:22.956] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:22.956] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:9:0) after 1m0s
[07:10:23.030] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:9:0)
[07:10:23.030] Timer tick, asking Buildbucket for the build status
[07:10:23.218] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:23.218] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:10:0) after 1m0s
[07:11:23.259] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:10:0)
[07:11:23.259] Timer tick, asking Buildbucket for the build status
[07:11:23.433] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:23.433] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:11:0) after 1m0s
[07:12:23.515] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:11:0)
[07:12:23.515] Timer tick, asking Buildbucket for the build status
[07:12:24.017] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:24.017] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:12:0) after 1m0s
[07:13:24.089] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:12:0)
[07:13:24.089] Timer tick, asking Buildbucket for the build status
[07:13:24.269] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:24.269] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:13:0) after 1m0s
[07:14:24.465] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:13:0)
[07:14:24.465] Timer tick, asking Buildbucket for the build status
[07:14:24.608] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:24.608] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:14:0) after 1m0s
[07:15:24.660] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:14:0)
[07:15:24.660] Timer tick, asking Buildbucket for the build status
[07:15:24.944] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:24.944] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:15:0) after 1m0s
[07:16:24.992] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:15:0)
[07:16:24.992] Timer tick, asking Buildbucket for the build status
[07:16:25.141] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:25.141] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:16:0) after 1m0s
[07:17:25.144] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:16:0)
[07:17:25.144] Timer tick, asking Buildbucket for the build status
[07:17:25.266] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:25.266] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:17:0) after 1m0s
[07:18:25.318] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:17:0)
[07:18:25.318] Timer tick, asking Buildbucket for the build status
[07:18:25.495] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:25.495] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:18:0) after 1m0s
[07:19:25.560] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:18:0)
[07:19:25.560] Timer tick, asking Buildbucket for the build status
[07:19:25.753] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:25.753] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:19:0) after 1m0s
[07:20:25.840] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:19:0)
[07:20:25.840] Timer tick, asking Buildbucket for the build status
[07:20:26.807] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:26.807] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:20:0) after 1m0s
[07:21:26.878] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:20:0)
[07:21:26.878] Timer tick, asking Buildbucket for the build status
[07:21:26.995] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:26.995] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:21:0) after 1m0s
[07:22:27.040] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:21:0)
[07:22:27.040] Timer tick, asking Buildbucket for the build status
[07:22:27.162] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:27.162] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:22:0) after 1m0s
[07:23:27.357] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:22:0)
[07:23:27.357] Timer tick, asking Buildbucket for the build status
[07:23:27.486] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:27.486] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:23:0) after 1m0s
[07:24:27.534] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:23:0)
[07:24:27.534] Timer tick, asking Buildbucket for the build status
[07:24:27.937] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:27.937] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:24:0) after 1m0s
[07:25:28.018] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:24:0)
[07:25:28.018] Timer tick, asking Buildbucket for the build status
[07:25:28.141] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:28.141] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:25:0) after 1m0s
[07:26:28.214] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:25:0)
[07:26:28.214] Timer tick, asking Buildbucket for the build status
[07:26:28.338] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:28.338] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:26:0) after 1m0s
[07:27:28.409] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:26:0)
[07:27:28.409] Timer tick, asking Buildbucket for the build status
[07:27:28.524] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:28.524] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:27:0) after 1m0s
[07:28:28.555] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:27:0)
[07:28:28.555] Timer tick, asking Buildbucket for the build status
[07:28:28.660] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:28.660] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:28:0) after 1m0s
[07:29:28.674] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:28:0)
[07:29:28.674] Timer tick, asking Buildbucket for the build status
[07:29:28.967] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:28.967] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:29:0) after 1m0s
[07:30:28.994] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:29:0)
[07:30:28.994] Timer tick, asking Buildbucket for the build status
[07:30:29.103] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:29.103] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:30:0) after 1m0s
[07:31:29.174] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:30:0)
[07:31:29.174] Timer tick, asking Buildbucket for the build status
[07:31:29.253] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:29.253] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:31:0) after 1m0s
[07:32:29.313] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:31:0)
[07:32:29.313] Timer tick, asking Buildbucket for the build status
[07:32:29.397] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:29.397] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:32:0) after 1m0s
[07:33:29.449] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:32:0)
[07:33:29.449] Timer tick, asking Buildbucket for the build status
[07:33:30.174] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:30.174] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:33:0) after 1m0s
[07:34:30.301] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:33:0)
[07:34:30.301] Timer tick, asking Buildbucket for the build status
[07:34:30.370] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:30.370] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:34:0) after 1m0s
[07:35:30.451] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:34:0)
[07:35:30.451] Timer tick, asking Buildbucket for the build status
[07:35:30.784] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:30.784] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:35:0) after 1m0s
[07:36:30.822] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:35:0)
[07:36:30.822] Timer tick, asking Buildbucket for the build status
[07:36:30.947] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:30.947] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:36:0) after 1m0s
[07:37:30.974] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:36:0)
[07:37:30.974] Timer tick, asking Buildbucket for the build status
[07:37:31.064] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:31.064] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:37:0) after 1m0s
[07:38:31.217] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:37:0)
[07:38:31.217] Timer tick, asking Buildbucket for the build status
[07:38:31.612] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:31.612] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:38:0) after 1m0s
[07:39:31.714] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:38:0)
[07:39:31.714] Timer tick, asking Buildbucket for the build status
[07:39:32.042] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:32.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:39:0) after 1m0s
[07:40:32.015] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:39:0)
[07:40:32.015] Timer tick, asking Buildbucket for the build status
[07:40:32.186] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:32.186] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:40:0) after 1m0s
[07:41:32.184] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:40:0)
[07:41:32.184] Timer tick, asking Buildbucket for the build status
[07:41:32.459] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:32.459] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:41:0) after 1m0s
[07:42:32.518] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:41:0)
[07:42:32.518] Timer tick, asking Buildbucket for the build status
[07:42:32.603] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:32.603] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:42:0) after 1m0s
[07:43:32.661] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:42:0)
[07:43:32.661] Timer tick, asking Buildbucket for the build status
[07:43:32.816] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:32.816] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:43:0) after 1m0s
[07:44:32.878] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:43:0)
[07:44:32.878] Timer tick, asking Buildbucket for the build status
[07:44:33.007] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:33.007] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:44:0) after 1m0s
[07:45:33.055] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:44:0)
[07:45:33.055] Timer tick, asking Buildbucket for the build status
[07:45:33.198] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:33.198] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:45:0) after 1m0s
[07:46:33.248] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:45:0)
[07:46:33.248] Timer tick, asking Buildbucket for the build status
[07:46:33.559] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:33.559] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:46:0) after 1m0s
[07:47:33.616] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:46:0)
[07:47:33.616] Timer tick, asking Buildbucket for the build status
[07:47:33.688] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:33.688] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:47:0) after 1m0s
[07:48:33.715] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:47:0)
[07:48:33.715] Timer tick, asking Buildbucket for the build status
[07:48:33.889] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:33.889] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:48:0) after 1m0s
[07:49:33.945] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:48:0)
[07:49:33.945] Timer tick, asking Buildbucket for the build status
[07:49:34.088] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:34.088] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:49:0) after 1m0s
[07:50:34.126] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:49:0)
[07:50:34.126] Timer tick, asking Buildbucket for the build status
[07:50:34.283] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:34.283] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:50:0) after 1m0s
[07:51:34.305] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:50:0)
[07:51:34.305] Timer tick, asking Buildbucket for the build status
[07:51:34.386] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:34.387] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:51:0) after 1m0s
[07:52:34.430] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:51:0)
[07:52:34.431] Timer tick, asking Buildbucket for the build status
[07:52:34.505] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:34.505] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:52:0) after 1m0s
[07:53:34.615] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:52:0)
[07:53:34.615] Timer tick, asking Buildbucket for the build status
[07:53:34.902] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:34.902] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:53:0) after 1m0s
[07:54:34.945] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:53:0)
[07:54:34.945] Timer tick, asking Buildbucket for the build status
[07:54:35.048] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:35.048] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:54:0) after 1m0s
[07:55:35.069] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:54:0)
[07:55:35.069] Timer tick, asking Buildbucket for the build status
[07:55:35.151] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:35.151] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:55:0) after 1m0s
[07:56:35.225] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:55:0)
[07:56:35.225] Timer tick, asking Buildbucket for the build status
[07:56:35.955] Build 8861863506464134224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:35.955] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027293568591638944:56:0) after 1m0s
[07:57:21.138] Received PubSub notification, asking Buildbucket for the build status
[07:57:21.305] Build 8861863506464134224: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[07:57:21.305] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1607068639434834",
"created_by": "project:chromium",
"created_ts": "1607065400595524",
"id": "8861863506464134224",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f3d6ac247840332df6a7bab45cf614b913f28d24\"}}",
"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\": \"f3d6ac247840332df6a7bab45cf614b913f28d24\", \"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-9-v8md\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16019, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"1ad5791d97bea23f092b206679a56302defa378f\", \"got_dawn_revision\": \"10cb17e079e14f4f9a1bdaea9b46b343c82c4679\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"f3d6ac247840332df6a7bab45cf614b913f28d24\", \"got_revision_cp\": \"refs/heads/master@{#833632}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"bae138de13ac876cbca0e30a40b89dc808e2b29d\", \"got_v8_revision\": \"04e154e4ca48094b482c0f1d77803987ec7b7cc6\", \"got_v8_revision_cp\": \"refs/heads/8.9.98@{#1}\", \"got_webrtc_revision\": \"31d3b217d3b5c9e98559bb0ef9612659b36e88e5\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32766}\", \"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\": \"f3d6ac247840332df6a7bab45cf614b913f28d24\", \"swarm_hashes_refs/heads/master(at){#833632}_without_patch\": {\"absl_hardening_tests\": \"b7756871b76c5c8f76958c3b65646b96710a3dd1\", \"accessibility_unittests\": \"b1ae80605db79b5b7b2477e44b8369efc8b85f29\", \"angle_unittests\": \"e72423752f4ffaa0acadec0a32e049e123a1de58\", \"app_shell_unittests\": \"e5c09bf5221e02e6d9e09324d82d65d529de7e59\", \"aura_unittests\": \"301cadfdc07d1df8a8d655dedf7ed0cd615c03d5\", \"base_unittests\": \"d3d395a15b6e2153f68aabd69f1d092c9e7c78da\", \"base_util_unittests\": \"1699349db27a792e99eb8fc6ebb11a12a3bf5e29\", \"blink_common_unittests\": \"9646eca3956020bd1db15b4b1a10110e0415231c\", \"blink_fuzzer_unittests\": \"0a4adf02a93dda5d186ced8a6545100f8bdb05ca\", \"blink_heap_unittests\": \"6073d00a4e702d4aec5795385f80e7c78c771189\", \"blink_platform_unittests\": \"43c27a86b5f9b695ebf1284a6704e2a8a87bdfaa\", \"blink_unittests\": \"2c0ae30038895c78f862ccd1d276afe7bf9d5603\", \"boringssl_crypto_tests\": \"2a99d76fb3aa71918e26b158a081fb316dc1fc01\", \"boringssl_ssl_tests\": \"31d5e8b91d72b678d61592480e3cf8833a9a50ff\", \"browser_switcher_bho_unittests\": \"87964c28a7f623db64c5a68c90ef1bcab6e3b070\", \"browser_tests\": \"947ab62e8e2338fa56e804aeb06571810a602c1b\", \"capture_unittests\": \"078a76c8ea39f3f3ba048fc7813fabb81e4cd070\", \"cast_unittests\": \"0a7f103c4f5e466e09d329c8d2919e0e87226d32\", \"cc_unittests\": \"e840d7e18c8fd5fa53fc3d045a184b9e19ec0fa2\", \"chrome_app_unittests\": \"4353751d1299d4a5461823baff1a47b70cb123d8\", \"chrome_cleaner_unittests\": \"867350ed4448013ccaded445d3b8386031881c4a\", \"chrome_elf_unittests\": \"4ccf21b92ead58c2560ee87050b94104d7aa3e1a\", \"chromedriver_unittests\": \"4df24af3c6849d69f9e12da4c8b62754eed6808c\", \"components_browsertests\": \"f666dd4c5fecd4022f76ee83b776ba375a7e2b40\", \"components_unittests\": \"c285ea416c41225e2ab3bb2bf3c65954befd635b\", \"compositor_unittests\": \"de1a908b132581f032381142f7acdd4c6cfb0129\", \"content_browsertests\": \"56dd0afeaf7bbd5fa85fb3faeec1cc4f58d8af67\", \"content_unittests\": \"9f2e57abcaf83aec2ee7a27652cc1a7184894ef1\", \"courgette_unittests\": \"ecd1a3d3372684f2322baa183813c64a0438d2f1\", \"crashpad_tests\": \"a6d764f38b76722e3e5170d9b26abbac3f0ff477\", \"cronet_tests\": \"189867476c3aebcbbbf63adc589e0ef924ac78a1\", \"cronet_unittests\": \"214b69cf027eff0596f6b656170dee3c138ed2b1\", \"crypto_unittests\": \"796e65fb3824b4989ad7e61b47c4fe26f4e2bdbd\", \"delayloads_unittests\": \"45b1e83a70cf9230ca208b52bca4eb2abb16c5b4\", \"device_unittests\": \"7a32652afd3acac4e0afc4aad1f2a711888669cd\", \"display_unittests\": \"e7cb4b24c0e08df9dc66ad703c9abaefe09f1d7d\", \"elevation_service_unittests\": \"a3f9532a5ab6332866040c6d3ce242aba67717eb\", \"events_unittests\": \"50da958f62006a7e65465ce958511f034dfc512e\", \"extensions_browsertests\": \"103d2c26ab556c80843cc1f854f70ed10a9384a0\", \"extensions_unittests\": \"4a39a1b61c1b4488bb5117bc7b469d4728347b15\", \"filesystem_service_unittests\": \"01cc936fde237aa4c8f1fb9978708b08be2f76a8\", \"gcm_unit_tests\": \"9d7c3b7d01fa1164362bae753d6e13449440b865\", \"gcp_unittests\": \"59e3162d42f6c66e001dc957cb7cc9bfea950c46\", \"gfx_unittests\": \"dfe99dd86a00eee15e819662b6bf7512f15a4142\", \"gin_unittests\": \"52b2d321a787670b89e773efcc993c3cecf2f9e6\", \"google_apis_unittests\": \"0ca6844850b4300a553dcc882e10787378c92320\", \"gpu_unittests\": \"782203d8479f2ab2c4231f3905859a8a8a0bdee5\", \"gwp_asan_unittests\": \"93cac1d852cbcaa6e4223d3961c80836796dc6d3\", \"headless_browsertests\": \"3285a86a70d022c44a728efd64ccc32c5502c1b3\", \"headless_unittests\": \"0a051856a7fc47080eac3d45ce804b699837b4f2\", \"install_static_unittests\": \"ffc77995ca513d00b1cb6f5e1ac959b7644e9bc4\", \"installer_util_unittests\": \"88b09bf1744aefbe67fe848cf2bb3337f37cf102\", \"interactive_ui_tests\": \"a8a09690c5602b5107b50923683465502ee6a35c\", \"ipc_tests\": \"1db60d4f2ae6103578e51c4f08d4bcd65aee6931\", \"jingle_unittests\": \"ff77b8cbd17e91c53e93f47bf2787200ca51215c\", \"latency_unittests\": \"e9e3a48c078ed2d99fab5a489e582a0df167e00d\", \"libjingle_xmpp_unittests\": \"e1470d3dbb39087b1a9c0d478b242ce9cddfa431\", \"liburlpattern_unittests\": \"21de48be7e51314cd70b29136416a837f3610650\", \"media_blink_unittests\": \"0fa6d198d32da5d00966ed8048b98af1817ea24d\", \"media_unittests\": \"d8cabf7077fe0d5d114927085c3be5ac5b84c846\", \"message_center_unittests\": \"38c46ab1a7b8019557b68745d021f6aa2a256a18\", \"midi_unittests\": \"a1aaf4435a7fbeda8e4e9d1586567124a7ddd45b\", \"mojo_core_unittests\": \"ae18bc36b3563c5abe80832c2005c84c63263c4f\", \"mojo_unittests\": \"8e4d414e9c8c26b0bcf83481ace6528e901fc29c\", \"nacl_loader_unittests\": \"835969585d55f9f92b04cb5dd373e7560345ccbe\", \"native_theme_unittests\": \"c12ceb106274f7baedba899b9a16507234b70855\", \"net_unittests\": \"aae9a0f3b2cc83df739d0750fa81f5a44a1fdab0\", \"notification_helper_unittests\": \"fbf42c7546eef309399f026d7b73cfb3c59f0074\", \"pdf_unittests\": \"9b4588155444f60d76d72b71cf219a742ebbc799\", \"perfetto_unittests\": \"e56fdd95c28e1e1de8b96ec3f09d5e95dee8822c\", \"ppapi_unittests\": \"aa9834a682f27ccea1555120bacc6a7a7078081a\", \"printing_unittests\": \"88d2248b5785c23cec6f7cbfd5720912c95015d1\", \"remoting_unittests\": \"b1f734f0b9247311004327c9a21371aeae619e69\", \"sbox_integration_tests\": \"d2fa8db8b4740eec10235bc7985ec56d3179986c\", \"sbox_unittests\": \"69a82b0364eb1dcb6957ef4e40f6d71b823ae847\", \"sbox_validation_tests\": \"9231cd0b5cc442d07029ac72db9f5a466ba4febf\", \"service_manager_unittests\": \"9e75ce78d6b45df81efc6a1ba4415305b0342369\", \"services_unittests\": \"e226bb641c82b45555409f7a626ec533bd9cb4ad\", \"setup_unittests\": \"c0b74c4d4f05804eb16a6939a0bbfe043613298c\", \"shell_dialogs_unittests\": \"5c6f8e8f1f232f65e733f85d0f27c7e79719c675\", \"skia_unittests\": \"aabd0f7eca09c43e766b99c064c561f2b169d181\", \"snapshot_unittests\": \"a273f5e6dd9cae3da2e9a626712e97d324cddd5d\", \"sql_unittests\": \"96e9c602bb664c98bec84967bb23e9c28806b91b\", \"storage_unittests\": \"1747c2f997dfed5c767abbcd68d485c4624c9d0f\", \"sync_integration_tests\": \"b1f0047da73e47c69f644494bdebb2a932ea0bb8\", \"traffic_annotation_auditor_unittests\": \"3ed4540dd979161cc88a2f390db2a3ffb1ab760d\", \"ui_base_unittests\": \"badc0fefef2381bf3f47ac2097fd4a4aedcb1b8b\", \"ui_touch_selection_unittests\": \"e0127f407a8bb8f61e2dab2320f262508680574a\", \"unit_tests\": \"1e5736b7f9069d1042516e637da03ebf324f8b47\", \"updater_tests\": \"2a5625e5fd58893b2c2320ba4e4837b3bf274668\", \"url_unittests\": \"923e9b67d2af85706269add562adc0aaaa00aa1e\", \"views_unittests\": \"95a7e4ff5728ac9d3cc8b859d69913dd9605da43\", \"viz_unittests\": \"1df90a625d2114ea5461e2d66786403a3a255592\", \"vr_common_unittests\": \"ea00d4923fe426e78f53d7adae0232b097761e39\", \"vr_pixeltests\": \"892b4cd84ef723f1d7c941a4476729ffc511d549\", \"weblayer_browsertests\": \"a588abb3b74926a0bbbe554156e974a6e48c643e\", \"weblayer_unittests\": \"8e816534be65a98b5a8df63b8eb835378f58bd36\", \"wm_unittests\": \"0d946885c154d1ecf8430afad30806ee837e052c\", \"wtf_unittests\": \"53d8f9940cbf02e1d9fc83d9e7f0b000a5302c1c\", \"zlib_unittests\": \"3dea71dbee3fc737ec98d921566bfe3fed38e233\", \"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-9-v8md\"], \"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": "1607065405052566",
"status": "COMPLETED",
"status_changed_ts": "1607068639680717",
"tags": [
"build_address:luci.chromium.ci/win-asan/16019",
"builder:win-asan",
"buildset:commit/git/f3d6ac247840332df6a7bab45cf614b913f28d24",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f3d6ac247840332df6a7bab45cf614b913f28d24",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027293568591638944",
"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/8861863506464134224/+/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:50456186e029f410",
"user_agent:luci-scheduler"
],
"updated_ts": "1607068639685109",
"url": "https://ci.chromium.org/b/8861863506464134224",
"utcnow_ts": "1607068641290917"
}
}
[07:57:21.305] Invocation finished in 54m2.12241463s with status SUCCEEDED