[07:22:53.156] New invocation is queued and will start shortly
[07:22:54.483] Starting the invocation (attempt 1)
[07:22:54.524] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:22:54.527] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:22:54.527] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9028651292106415584",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"5a639d162f3fb8a7cf1a152ee14e89a36453527c\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9028651292106415584",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/5a639d162f3fb8a7cf1a152ee14e89a36453527c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5a639d162f3fb8a7cf1a152ee14e89a36453527c",
"gitiles_ref:refs/heads/master"
]
}
[07:22:55.221] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1605770574625467",
"id": "8863221229900244688",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5a639d162f3fb8a7cf1a152ee14e89a36453527c\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1605770575079344",
"tags": [
"build_address:luci.chromium.ci/win-asan/15718",
"builder:win-asan",
"buildset:commit/git/5a639d162f3fb8a7cf1a152ee14e89a36453527c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5a639d162f3fb8a7cf1a152ee14e89a36453527c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028651292106415584",
"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/8863221229900244688/+/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": "1605770575079523",
"url": "https://ci.chromium.org/b/8863221229900244688",
"utcnow_ts": "1605770575211152"
}
}
[07:22:55.221] Task URL: https://ci.chromium.org/b/8863221229900244688
[07:22:55.221] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:2:0) after 1m0s
[07:23:02.870] Received PubSub notification, asking Buildbucket for the build status
[07:23:02.985] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:55.282] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:2:0)
[07:23:55.282] Timer tick, asking Buildbucket for the build status
[07:23:55.376] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:55.376] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:4:0) after 1m0s
[07:24:55.529] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:4:0)
[07:24:55.529] Timer tick, asking Buildbucket for the build status
[07:24:55.626] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:55.626] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:5:0) after 1m0s
[07:25:55.723] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:5:0)
[07:25:55.723] Timer tick, asking Buildbucket for the build status
[07:25:55.816] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:55.816] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:6:0) after 1m0s
[07:26:55.819] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:6:0)
[07:26:55.819] Timer tick, asking Buildbucket for the build status
[07:26:55.934] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:55.934] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:7:0) after 1m0s
[07:27:55.982] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:7:0)
[07:27:55.982] Timer tick, asking Buildbucket for the build status
[07:27:56.155] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:56.155] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:8:0) after 1m0s
[07:28:56.236] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:8:0)
[07:28:56.236] Timer tick, asking Buildbucket for the build status
[07:28:57.046] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:57.046] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:9:0) after 1m0s
[07:29:57.103] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:9:0)
[07:29:57.103] Timer tick, asking Buildbucket for the build status
[07:29:57.415] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:57.415] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:10:0) after 1m0s
[07:30:57.557] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:10:0)
[07:30:57.557] Timer tick, asking Buildbucket for the build status
[07:30:57.627] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:57.627] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:11:0) after 1m0s
[07:31:57.680] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:11:0)
[07:31:57.680] Timer tick, asking Buildbucket for the build status
[07:31:57.891] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:57.891] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:12:0) after 1m0s
[07:32:57.870] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:12:0)
[07:32:57.870] Timer tick, asking Buildbucket for the build status
[07:32:57.978] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:57.978] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:13:0) after 1m0s
[07:33:58.003] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:13:0)
[07:33:58.003] Timer tick, asking Buildbucket for the build status
[07:33:58.250] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:58.250] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:14:0) after 1m0s
[07:34:58.361] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:14:0)
[07:34:58.361] Timer tick, asking Buildbucket for the build status
[07:34:58.576] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:58.576] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:15:0) after 1m0s
[07:35:58.744] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:15:0)
[07:35:58.744] Timer tick, asking Buildbucket for the build status
[07:35:58.835] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:58.835] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:16:0) after 1m0s
[07:36:58.935] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:16:0)
[07:36:58.935] Timer tick, asking Buildbucket for the build status
[07:36:59.052] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:59.052] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:17:0) after 1m0s
[07:37:59.317] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:17:0)
[07:37:59.317] Timer tick, asking Buildbucket for the build status
[07:37:59.487] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:59.487] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:18:0) after 1m0s
[07:38:59.625] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:18:0)
[07:38:59.625] Timer tick, asking Buildbucket for the build status
[07:38:59.989] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:59.989] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:19:0) after 1m0s
[07:40:00.330] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:19:0)
[07:40:00.330] Timer tick, asking Buildbucket for the build status
[07:40:00.468] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:00.468] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:20:0) after 1m0s
[07:41:01.416] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:20:0)
[07:41:01.416] Timer tick, asking Buildbucket for the build status
[07:41:01.523] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:01.523] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:21:0) after 1m0s
[07:42:01.516] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:21:0)
[07:42:01.516] Timer tick, asking Buildbucket for the build status
[07:42:01.598] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:01.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:22:0) after 1m0s
[07:43:01.719] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:22:0)
[07:43:01.719] Timer tick, asking Buildbucket for the build status
[07:43:01.866] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:01.866] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:23:0) after 1m0s
[07:44:02.453] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:23:0)
[07:44:02.453] Timer tick, asking Buildbucket for the build status
[07:44:02.684] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:02.684] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:24:0) after 1m0s
[07:45:02.903] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:24:0)
[07:45:02.903] Timer tick, asking Buildbucket for the build status
[07:45:03.855] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:03.855] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:25:0) after 1m0s
[07:46:04.020] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:25:0)
[07:46:04.020] Timer tick, asking Buildbucket for the build status
[07:46:04.166] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:04.166] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:26:0) after 1m0s
[07:47:04.293] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:26:0)
[07:47:04.293] Timer tick, asking Buildbucket for the build status
[07:47:04.418] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:04.418] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:27:0) after 1m0s
[07:48:04.506] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:27:0)
[07:48:04.506] Timer tick, asking Buildbucket for the build status
[07:48:04.661] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:04.661] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:28:0) after 1m0s
[07:49:04.834] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:28:0)
[07:49:04.834] Timer tick, asking Buildbucket for the build status
[07:49:05.076] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:05.076] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:29:0) after 1m0s
[07:50:05.182] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:29:0)
[07:50:05.182] Timer tick, asking Buildbucket for the build status
[07:50:05.321] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:05.321] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:30:0) after 1m0s
[07:51:05.513] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:30:0)
[07:51:05.513] Timer tick, asking Buildbucket for the build status
[07:51:05.964] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:05.964] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:31:0) after 1m0s
[07:52:06.107] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:31:0)
[07:52:06.107] Timer tick, asking Buildbucket for the build status
[07:52:06.221] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:06.221] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:32:0) after 1m0s
[07:53:06.331] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:32:0)
[07:53:06.331] Timer tick, asking Buildbucket for the build status
[07:53:06.554] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:06.554] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:33:0) after 1m0s
[07:54:06.593] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:33:0)
[07:54:06.593] Timer tick, asking Buildbucket for the build status
[07:54:06.710] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:06.710] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:34:0) after 1m0s
[07:55:06.755] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:34:0)
[07:55:06.755] Timer tick, asking Buildbucket for the build status
[07:55:07.174] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:07.174] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:35:0) after 1m0s
[07:56:07.258] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:35:0)
[07:56:07.258] Timer tick, asking Buildbucket for the build status
[07:56:07.483] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:07.483] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:36:0) after 1m0s
[07:57:07.569] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:36:0)
[07:57:07.569] Timer tick, asking Buildbucket for the build status
[07:57:07.638] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:07.638] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:37:0) after 1m0s
[07:58:07.728] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:37:0)
[07:58:07.728] Timer tick, asking Buildbucket for the build status
[07:58:07.870] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:07.870] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:38:0) after 1m0s
[07:59:07.907] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:38:0)
[07:59:07.907] Timer tick, asking Buildbucket for the build status
[07:59:08.087] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:08.087] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:39:0) after 1m0s
[08:00:08.203] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:39:0)
[08:00:08.203] Timer tick, asking Buildbucket for the build status
[08:00:08.718] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:08.718] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:40:0) after 1m0s
[08:01:09.015] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:40:0)
[08:01:09.015] Timer tick, asking Buildbucket for the build status
[08:01:09.082] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:09.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:41:0) after 1m0s
[08:02:09.222] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:41:0)
[08:02:09.222] Timer tick, asking Buildbucket for the build status
[08:02:10.290] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:10.290] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:42:0) after 1m0s
[08:03:10.416] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:42:0)
[08:03:10.417] Timer tick, asking Buildbucket for the build status
[08:03:12.048] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:12.048] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:43:0) after 1m0s
[08:04:12.277] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:43:0)
[08:04:12.278] Timer tick, asking Buildbucket for the build status
[08:04:12.363] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:12.363] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:44:0) after 1m0s
[08:05:12.446] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:44:0)
[08:05:12.446] Timer tick, asking Buildbucket for the build status
[08:05:12.551] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:12.551] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:45:0) after 1m0s
[08:06:12.619] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:45:0)
[08:06:12.619] Timer tick, asking Buildbucket for the build status
[08:06:12.746] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:12.750] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:46:0) after 1m0s
[08:07:12.826] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:46:0)
[08:07:12.826] Timer tick, asking Buildbucket for the build status
[08:07:13.028] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:13.028] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:47:0) after 1m0s
[08:08:13.213] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:47:0)
[08:08:13.213] Timer tick, asking Buildbucket for the build status
[08:08:13.325] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:13.325] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:48:0) after 1m0s
[08:09:13.380] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:48:0)
[08:09:13.380] Timer tick, asking Buildbucket for the build status
[08:09:13.497] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:13.497] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:49:0) after 1m0s
[08:10:13.712] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:49:0)
[08:10:13.712] Timer tick, asking Buildbucket for the build status
[08:10:13.823] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:13.823] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:50:0) after 1m0s
[08:11:13.846] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:50:0)
[08:11:13.846] Timer tick, asking Buildbucket for the build status
[08:11:14.650] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:14.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:51:0) after 1m0s
[08:12:14.810] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:51:0)
[08:12:14.810] Timer tick, asking Buildbucket for the build status
[08:12:14.953] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:14.953] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:52:0) after 1m0s
[08:13:15.024] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:52:0)
[08:13:15.024] Timer tick, asking Buildbucket for the build status
[08:13:15.206] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:15.206] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:53:0) after 1m0s
[08:14:15.332] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:53:0)
[08:14:15.332] Timer tick, asking Buildbucket for the build status
[08:14:15.445] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:15.445] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:54:0) after 1m0s
[08:15:15.491] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:54:0)
[08:15:15.491] Timer tick, asking Buildbucket for the build status
[08:15:15.907] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:15.908] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:55:0) after 1m0s
[08:16:15.931] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:55:0)
[08:16:15.931] Timer tick, asking Buildbucket for the build status
[08:16:16.021] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:16.021] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:56:0) after 1m0s
[08:17:16.102] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:56:0)
[08:17:16.102] Timer tick, asking Buildbucket for the build status
[08:17:16.185] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:16.185] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:57:0) after 1m0s
[08:18:16.294] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:57:0)
[08:18:16.294] Timer tick, asking Buildbucket for the build status
[08:18:16.735] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:16.735] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:58:0) after 1m0s
[08:19:16.808] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:58:0)
[08:19:16.808] Timer tick, asking Buildbucket for the build status
[08:19:17.415] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:17.415] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:59:0) after 1m0s
[08:20:17.541] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:59:0)
[08:20:17.541] Timer tick, asking Buildbucket for the build status
[08:20:18.111] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:18.111] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:60:0) after 1m0s
[08:21:18.178] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:60:0)
[08:21:18.178] Timer tick, asking Buildbucket for the build status
[08:21:18.261] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:18.261] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:61:0) after 1m0s
[08:22:18.284] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:61:0)
[08:22:18.284] Timer tick, asking Buildbucket for the build status
[08:22:18.365] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:18.365] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:62:0) after 1m0s
[08:23:18.443] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:62:0)
[08:23:18.443] Timer tick, asking Buildbucket for the build status
[08:23:18.613] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:18.613] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:63:0) after 1m0s
[08:24:18.632] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:63:0)
[08:24:18.632] Timer tick, asking Buildbucket for the build status
[08:24:18.722] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:18.722] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:64:0) after 1m0s
[08:25:18.749] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:64:0)
[08:25:18.749] Timer tick, asking Buildbucket for the build status
[08:25:19.040] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:19.040] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:65:0) after 1m0s
[08:26:19.141] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:65:0)
[08:26:19.141] Timer tick, asking Buildbucket for the build status
[08:26:19.556] Build 8863221229900244688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:19.556] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028651292106415584:66:0) after 1m0s
[08:26:22.150] Received PubSub notification, asking Buildbucket for the build status
[08:26:22.244] Build 8863221229900244688: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[08:26:22.244] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1605774380929231",
"created_by": "project:chromium",
"created_ts": "1605770574625467",
"id": "8863221229900244688",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5a639d162f3fb8a7cf1a152ee14e89a36453527c\"}}",
"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\": \"5a639d162f3fb8a7cf1a152ee14e89a36453527c\", \"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-5-fw95\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15718, \"clang_revision\": \"llvmorg-12-init-11780-g6ef07111-1\", \"got_angle_revision\": \"8b76d37f9f9af22be8f1a31056b8a28992990662\", \"got_dawn_revision\": \"ce207be41fd6d9f1334ca6f251812511dc63faca\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"5a639d162f3fb8a7cf1a152ee14e89a36453527c\", \"got_revision_cp\": \"refs/heads/master@{#829122}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"060020166679a265a2bc8250f4d78a7a8c29de89\", \"got_v8_revision\": \"8645ae9d96dc816b29ce5ff58430c8e8d07be20a\", \"got_v8_revision_cp\": \"refs/heads/8.9.12@{#1}\", \"got_webrtc_revision\": \"afaca889d46b2c493de34a8f0771c89dea17bb1a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32614}\", \"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\": \"5a639d162f3fb8a7cf1a152ee14e89a36453527c\", \"swarm_hashes_refs/heads/master(at){#829122}_without_patch\": {\"absl_hardening_tests\": \"990d737231e835dfe9827e0707e137bdbb0a3e28\", \"accessibility_unittests\": \"6dd71a02d8b812fffd6456be1187d0cb0225e576\", \"angle_unittests\": \"7dcdc7281f023bec91919ae6884a2564d00431fe\", \"app_shell_unittests\": \"e2b7b901436a2e9afb6508b07444b2d5d671d4a0\", \"aura_unittests\": \"0e75a63871aa40aad70d5f3e6d7887024b50b1b1\", \"base_unittests\": \"6ca0c4f49251dc68134729849d65fcfbcc3e736f\", \"base_util_unittests\": \"d3c07f270a830a1465079bba0241af290e49aa6e\", \"blink_common_unittests\": \"5a72e29beaa93729df83c952471db5f41b218284\", \"blink_fuzzer_unittests\": \"948ca058c60a4b1ec3becd156afe10a8eef00653\", \"blink_heap_unittests\": \"009ed65595f1485e23e4ab532d3aab1372829ada\", \"blink_platform_unittests\": \"1cb8083cb776a0c12eb12b7fa16fcf88e7805fe2\", \"blink_unittests\": \"6121d2abc85be3cba51d5227cb5f738553b8a7c1\", \"boringssl_crypto_tests\": \"aeca3c1bc5b282b79535ab9348be19472fac00cf\", \"boringssl_ssl_tests\": \"ccc68487c7ac10d164eed2ef2ed828755eb794c2\", \"browser_switcher_bho_unittests\": \"49bbefffec11ad089232b1e85262e352af5d09e4\", \"browser_tests\": \"736299ded90b785d350ab60556ec55cfe50c700b\", \"capture_unittests\": \"bd244d264a80ff533072481000593e4bef53a8a5\", \"cast_unittests\": \"87d13f642c7067fbbd32a5885d78d0cb5fec62de\", \"cc_unittests\": \"328101a3ba7073cd7fb5a813f70f58a00a3511c4\", \"chrome_app_unittests\": \"15e125d0fc43fc0c7b3259c2a2e327b57289509e\", \"chrome_cleaner_unittests\": \"f188c520976c94a47400c8758418bdd80cc1275f\", \"chrome_elf_unittests\": \"3bb844f4006ef9446730b92cd115d03e2e4d9fc1\", \"chromedriver_unittests\": \"9958ba919ebe878974b69914ff2452c0b0eefe5f\", \"components_browsertests\": \"da8a446f1c13195b8566c64806db44278b71f718\", \"components_unittests\": \"c35c140aee28d89b0f1e320f398a22be9ecfdf1d\", \"compositor_unittests\": \"2ad429b2c593a7379ced67158b77f9bf4c5bdf41\", \"content_browsertests\": \"eac8449d2d43b61d336ec3905fa32ffd82775514\", \"content_unittests\": \"d29d4e884e7f41b0eff01dca7abc5d209ed63c7c\", \"courgette_unittests\": \"e377e8f1da2bcdce5356534daab4cd99da00f0fc\", \"crashpad_tests\": \"296afb8350a8b16470ed1ab3fcd8a9d612f4e796\", \"cronet_tests\": \"8c34a62acdfcc915f1763e2db68a2d569fd1f994\", \"cronet_unittests\": \"d584c374b93b8a36ed5021df770506f0cf9bb829\", \"crypto_unittests\": \"74bbadfcc7484afeda584c458f146270f44b26c5\", \"delayloads_unittests\": \"1edcd78b1a77a0a45e95c8bc66d56c91c99e99a4\", \"device_unittests\": \"cbe3695a108244f88db860855ee3775edd7be26a\", \"display_unittests\": \"1a03d054eb2ea65c8fbddec938dced86b31e527a\", \"elevation_service_unittests\": \"07de6dcffce458f73a4165bf9667f41dc7827ba4\", \"events_unittests\": \"6845463eb2ece7f33e96c52e269a2a8a404c4c5e\", \"extensions_browsertests\": \"178ff539ed348014e19f6fe67b48d8e0c7c18a34\", \"extensions_unittests\": \"a9b98eec1b6ad1842d952dd88f33529b66b776c4\", \"filesystem_service_unittests\": \"b2459fd14f75249f564fd2dc493207b986d9b15f\", \"gcm_unit_tests\": \"1629ada2504aae06f1779249067d399b2899cc25\", \"gcp_unittests\": \"a26458ee97dfd877d8030159e33248865d0ca835\", \"gfx_unittests\": \"ad51ee6735c5638c4f9acce6646a743d66a3124f\", \"gin_unittests\": \"42539b128bc62b9e8112b8067e45b94fcfab4c22\", \"google_apis_unittests\": \"eac68b322ee0eeea2faad199a1eeec0b3ebe2fd8\", \"gpu_unittests\": \"0370b0b995fece956e5ead476281ce8f5343e1f9\", \"gwp_asan_unittests\": \"21c693dac7952cca6c5754444b197f514c98dbe9\", \"headless_browsertests\": \"3dc1d7b2d88ed3594e9ae61d7c8ae550dd956f14\", \"headless_unittests\": \"e9752060f4bee1ba8a602e536e31aa35b6ce7029\", \"install_static_unittests\": \"76f66f8d0932489d7184370f6bf9277b93fdca36\", \"installer_util_unittests\": \"89d8258f6795c11f32cd0257ef31d5df961de9b6\", \"interactive_ui_tests\": \"4d544369bdc1f505c05b95701ffd4f953dde60fa\", \"ipc_tests\": \"a8abdfac085d826416e7660d9b21ecb50e3cc467\", \"jingle_unittests\": \"967a7b93b0fac835724b667c8d0a096ef05d617e\", \"latency_unittests\": \"e6d50003039315c0bd0bb6cca980af1100892856\", \"libjingle_xmpp_unittests\": \"896ddfc6230fe0c2ff0f06f472350441f2557d39\", \"liburlpattern_unittests\": \"e0ee7d7944b9ee504440e4f9e627b93e32e9a9fd\", \"media_blink_unittests\": \"6cb88b3b6cfab082e0436b1248181c74be6ce3a4\", \"media_unittests\": \"228f69c0512f5cf4bc22c3db6f361c9d7c40b408\", \"message_center_unittests\": \"1237234b5dcc2329c2bdc7f6fd3c29900e8ba213\", \"midi_unittests\": \"36c8da313091aacbe254c573aae4c08e1a24e684\", \"mojo_core_unittests\": \"686e6d4f003c9899ab940db271f66aeab9f9628c\", \"mojo_unittests\": \"9bbc7269335d1819e74e9e16a892bdd26f7dc8d3\", \"nacl_loader_unittests\": \"26bf9e221e8de61c553b89bad0e725c87168d719\", \"native_theme_unittests\": \"35a5d81676cdcc27aeef5c62faa63491f8dc4d17\", \"net_unittests\": \"8f940cb4816dd047f88c053bae68467d33b49bb5\", \"notification_helper_unittests\": \"5bfae9ebec0fd414713c7a50e4ae818fb16268ef\", \"pdf_unittests\": \"a153ddc18b1c50783fb105f994d444c63856b13d\", \"perfetto_unittests\": \"1ce264301dd83db05ef84614d0f87e67bcd9022d\", \"ppapi_unittests\": \"ffa7292f7bab55d586d78b27bf7778e57b60de56\", \"printing_unittests\": \"0ff0f8b1cf99a2a1d9b67925e2c412132fe68b20\", \"remoting_unittests\": \"fdbb94135204042c8413e542b9aa7b3755833e7c\", \"sbox_integration_tests\": \"c852b4fb2f62199851e6a8df78fcbcdf0bd5f99c\", \"sbox_unittests\": \"dbcaa926e2dec8bc79372433699785a0e91f229c\", \"sbox_validation_tests\": \"62f9fc90634eaf0f706ee4dad2a061eecb048256\", \"service_manager_unittests\": \"d1a8817113cea857ac6130a0709c38d3313c3cfd\", \"services_unittests\": \"f001f17975857e420831d725d55c020aca797ed4\", \"setup_unittests\": \"ea0f2221e34be6f9c79607f6244d6cf0486b3ca8\", \"shell_dialogs_unittests\": \"369ff8ee600f32c52b064e447de5700d3b309574\", \"skia_unittests\": \"f43074b6a7bcc906b46e162a99c0a344469acd88\", \"snapshot_unittests\": \"38b1fe296994c377ef9ba96bbb163acf044466ed\", \"sql_unittests\": \"2d39ac066e18d31a63e59d121132b0e0194a6367\", \"storage_unittests\": \"0519182b6f579574c4d04b82791dee60f3cb33b0\", \"sync_integration_tests\": \"bf5dfdef30ed37f235c47bdd96164b26af107ddd\", \"traffic_annotation_auditor_unittests\": \"5172ec8caa64b18a32dadeefbed6348bf20290e5\", \"ui_base_unittests\": \"b7aef58c63478a878adafe47aef3c1fe174ebca8\", \"ui_touch_selection_unittests\": \"6b2d78fece9a82cbc14d7a9f536205abb11486be\", \"unit_tests\": \"7c58257bd990d91b3b21cdba55a451cfbc06db1b\", \"updater_tests\": \"b63f4fcc94464fc7566462d431e9343d2bc94f32\", \"url_unittests\": \"ca716c81cc84bee6a7d2803b434eb44b75dcb44e\", \"views_unittests\": \"5255b007f71977ab06881b768a2bb7ab74bb98dd\", \"viz_unittests\": \"88dd28498edbfb9cec0c5d2a321887747388dc14\", \"vr_common_unittests\": \"459f2ed30b136e70b3c1328f0a0da574c88f48e0\", \"vr_pixeltests\": \"649077372c11c3dfb36f43cb6e89e722ef1d6f38\", \"weblayer_browsertests\": \"e40397546b29747f4d8073f48bfe71495f56e898\", \"weblayer_unittests\": \"9057813c7eb3b420cdef7c7b1f438f9737e4f92f\", \"wm_unittests\": \"216cd7f70679f1751da5e35e9a765d42ec593a06\", \"wtf_unittests\": \"5818a29c38a65e115a5944ebb02716a753a8aa62\", \"zlib_unittests\": \"aea3552d16ba0680d919671c4bb9d33cf38f264c\", \"zucchini_unittests\": \"38504db4100acea5a66b3efe02fbfbb512ec3770\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_02613bacee8cd1c41158238da4ce7615021ecb28a4b52e6029f89cb601af0790_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-5-fw95\"], \"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\": [\"5637-4d8b832\"], \"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": "1605770581382792",
"status": "COMPLETED",
"status_changed_ts": "1605774381329104",
"tags": [
"build_address:luci.chromium.ci/win-asan/15718",
"builder:win-asan",
"buildset:commit/git/5a639d162f3fb8a7cf1a152ee14e89a36453527c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5a639d162f3fb8a7cf1a152ee14e89a36453527c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028651292106415584",
"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/8863221229900244688/+/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:4ff83407d771ab10",
"user_agent:luci-scheduler"
],
"updated_ts": "1605774381339838",
"url": "https://ci.chromium.org/b/8863221229900244688",
"utcnow_ts": "1605774382234290"
}
}
[08:26:22.244] Invocation finished in 1h3m29.106245671s with status SUCCEEDED