[14:10:48.136] New invocation is queued and will start shortly
[14:10:49.310] Starting the invocation (attempt 1)
[14:10:49.364] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:10:49.364] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:10:49.364] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9027991449460870064",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"1c031986f763ebfdd9a6ee1c79cd36c70e805dc3\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9027991449460870064",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/1c031986f763ebfdd9a6ee1c79cd36c70e805dc3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1c031986f763ebfdd9a6ee1c79cd36c70e805dc3",
"gitiles_ref:refs/heads/master"
]
}
[14:10:51.623] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1606399849578239",
"id": "8862561387287225664",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1c031986f763ebfdd9a6ee1c79cd36c70e805dc3\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1606399851310317",
"tags": [
"build_address:luci.chromium.ci/win-asan/15864",
"builder:win-asan",
"buildset:commit/git/1c031986f763ebfdd9a6ee1c79cd36c70e805dc3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1c031986f763ebfdd9a6ee1c79cd36c70e805dc3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027991449460870064",
"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/8862561387287225664/+/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": "1606399851312681",
"url": "https://ci.chromium.org/b/8862561387287225664",
"utcnow_ts": "1606399851612593"
}
}
[14:10:51.623] Task URL: https://ci.chromium.org/b/8862561387287225664
[14:10:51.623] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:2:0) after 1m0s
[14:10:54.585] Received PubSub notification, asking Buildbucket for the build status
[14:10:54.743] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:51.672] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:2:0)
[14:11:51.672] Timer tick, asking Buildbucket for the build status
[14:11:51.771] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:51.771] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:4:0) after 1m0s
[14:12:51.871] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:4:0)
[14:12:51.871] Timer tick, asking Buildbucket for the build status
[14:12:52.038] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:52.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:5:0) after 1m0s
[14:13:52.064] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:5:0)
[14:13:52.064] Timer tick, asking Buildbucket for the build status
[14:13:52.219] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:52.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:6:0) after 1m0s
[14:14:52.242] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:6:0)
[14:14:52.242] Timer tick, asking Buildbucket for the build status
[14:14:52.345] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:52.345] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:7:0) after 1m0s
[14:15:52.392] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:7:0)
[14:15:52.392] Timer tick, asking Buildbucket for the build status
[14:15:52.600] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:52.600] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:8:0) after 1m0s
[14:16:52.608] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:8:0)
[14:16:52.608] Timer tick, asking Buildbucket for the build status
[14:16:52.702] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:52.702] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:9:0) after 1m0s
[14:17:52.725] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:9:0)
[14:17:52.725] Timer tick, asking Buildbucket for the build status
[14:17:52.793] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:52.793] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:10:0) after 1m0s
[14:18:53.190] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:10:0)
[14:18:53.190] Timer tick, asking Buildbucket for the build status
[14:18:53.289] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:53.289] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:11:0) after 1m0s
[14:19:53.444] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:11:0)
[14:19:53.444] Timer tick, asking Buildbucket for the build status
[14:19:53.508] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:53.508] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:12:0) after 1m0s
[14:20:53.638] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:12:0)
[14:20:53.638] Timer tick, asking Buildbucket for the build status
[14:20:53.795] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:53.795] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:13:0) after 1m0s
[14:21:53.837] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:13:0)
[14:21:53.837] Timer tick, asking Buildbucket for the build status
[14:21:53.914] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:53.914] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:14:0) after 1m0s
[14:22:53.968] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:14:0)
[14:22:53.968] Timer tick, asking Buildbucket for the build status
[14:22:54.363] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:54.363] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:15:0) after 1m0s
[14:23:54.389] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:15:0)
[14:23:54.389] Timer tick, asking Buildbucket for the build status
[14:23:54.909] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:54.909] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:16:0) after 1m0s
[14:24:55.030] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:16:0)
[14:24:55.030] Timer tick, asking Buildbucket for the build status
[14:24:55.103] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:55.103] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:17:0) after 1m0s
[14:25:55.148] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:17:0)
[14:25:55.148] Timer tick, asking Buildbucket for the build status
[14:25:57.314] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:57.314] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:18:0) after 1m0s
[14:26:57.377] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:18:0)
[14:26:57.377] Timer tick, asking Buildbucket for the build status
[14:26:57.444] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:57.444] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:19:0) after 1m0s
[14:27:57.466] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:19:0)
[14:27:57.466] Timer tick, asking Buildbucket for the build status
[14:27:57.523] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:57.523] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:20:0) after 1m0s
[14:28:57.545] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:20:0)
[14:28:57.545] Timer tick, asking Buildbucket for the build status
[14:28:57.608] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:57.608] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:21:0) after 1m0s
[14:29:57.678] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:21:0)
[14:29:57.678] Timer tick, asking Buildbucket for the build status
[14:29:57.752] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:57.752] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:22:0) after 1m0s
[14:30:57.771] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:22:0)
[14:30:57.771] Timer tick, asking Buildbucket for the build status
[14:30:57.877] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:57.877] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:23:0) after 1m0s
[14:31:57.928] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:23:0)
[14:31:57.928] Timer tick, asking Buildbucket for the build status
[14:31:58.071] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:58.071] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:24:0) after 1m0s
[14:32:58.115] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:24:0)
[14:32:58.115] Timer tick, asking Buildbucket for the build status
[14:32:58.224] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:58.224] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:25:0) after 1m0s
[14:33:58.245] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:25:0)
[14:33:58.245] Timer tick, asking Buildbucket for the build status
[14:33:58.360] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:58.360] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:26:0) after 1m0s
[14:34:58.522] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:26:0)
[14:34:58.522] Timer tick, asking Buildbucket for the build status
[14:34:58.621] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:58.621] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:27:0) after 1m0s
[14:35:58.716] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:27:0)
[14:35:58.716] Timer tick, asking Buildbucket for the build status
[14:36:00.848] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:00.848] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:28:0) after 1m0s
[14:37:00.871] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:28:0)
[14:37:00.871] Timer tick, asking Buildbucket for the build status
[14:37:00.986] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:00.986] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:29:0) after 1m0s
[14:38:01.404] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:29:0)
[14:38:01.404] Timer tick, asking Buildbucket for the build status
[14:38:01.539] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:01.539] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:30:0) after 1m0s
[14:39:01.550] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:30:0)
[14:39:01.550] Timer tick, asking Buildbucket for the build status
[14:39:01.683] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:01.683] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:31:0) after 1m0s
[14:40:01.738] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:31:0)
[14:40:01.738] Timer tick, asking Buildbucket for the build status
[14:40:01.936] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:01.936] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:32:0) after 1m0s
[14:41:02.018] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:32:0)
[14:41:02.018] Timer tick, asking Buildbucket for the build status
[14:41:02.148] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:02.148] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:33:0) after 1m0s
[14:42:02.196] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:33:0)
[14:42:02.196] Timer tick, asking Buildbucket for the build status
[14:42:02.313] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:02.313] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:34:0) after 1m0s
[14:43:02.371] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:34:0)
[14:43:02.371] Timer tick, asking Buildbucket for the build status
[14:43:02.485] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:02.485] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:35:0) after 1m0s
[14:44:02.530] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:35:0)
[14:44:02.530] Timer tick, asking Buildbucket for the build status
[14:44:02.653] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:02.653] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:36:0) after 1m0s
[14:45:02.868] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:36:0)
[14:45:02.868] Timer tick, asking Buildbucket for the build status
[14:45:03.042] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:03.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:37:0) after 1m0s
[14:46:03.359] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:37:0)
[14:46:03.359] Timer tick, asking Buildbucket for the build status
[14:46:03.951] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:03.951] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:38:0) after 1m0s
[14:47:04.100] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:38:0)
[14:47:04.100] Timer tick, asking Buildbucket for the build status
[14:47:04.278] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:04.278] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:39:0) after 1m0s
[14:48:04.403] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:39:0)
[14:48:04.403] Timer tick, asking Buildbucket for the build status
[14:48:04.607] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:04.607] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:40:0) after 1m0s
[14:49:04.728] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:40:0)
[14:49:04.728] Timer tick, asking Buildbucket for the build status
[14:49:04.895] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:04.895] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:41:0) after 1m0s
[14:50:04.973] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:41:0)
[14:50:04.973] Timer tick, asking Buildbucket for the build status
[14:50:05.100] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:05.100] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:42:0) after 1m0s
[14:51:05.286] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:42:0)
[14:51:05.286] Timer tick, asking Buildbucket for the build status
[14:51:05.519] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:05.519] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:43:0) after 1m0s
[14:52:05.707] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:43:0)
[14:52:05.707] Timer tick, asking Buildbucket for the build status
[14:52:05.824] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:05.824] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:44:0) after 1m0s
[14:53:05.887] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:44:0)
[14:53:05.887] Timer tick, asking Buildbucket for the build status
[14:53:06.043] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:06.043] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:45:0) after 1m0s
[14:54:06.164] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:45:0)
[14:54:06.164] Timer tick, asking Buildbucket for the build status
[14:54:06.457] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:06.457] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:46:0) after 1m0s
[14:55:06.475] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:46:0)
[14:55:06.475] Timer tick, asking Buildbucket for the build status
[14:55:06.676] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:06.676] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:47:0) after 1m0s
[14:56:06.662] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:47:0)
[14:56:06.662] Timer tick, asking Buildbucket for the build status
[14:56:07.038] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:07.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:48:0) after 1m0s
[14:57:07.073] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:48:0)
[14:57:07.073] Timer tick, asking Buildbucket for the build status
[14:57:07.162] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:07.162] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:49:0) after 1m0s
[14:58:07.185] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:49:0)
[14:58:07.185] Timer tick, asking Buildbucket for the build status
[14:58:07.381] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:07.381] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:50:0) after 1m0s
[14:59:07.432] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:50:0)
[14:59:07.432] Timer tick, asking Buildbucket for the build status
[14:59:07.681] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:07.681] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:51:0) after 1m0s
[15:00:07.714] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:51:0)
[15:00:07.714] Timer tick, asking Buildbucket for the build status
[15:00:08.246] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:08.246] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:52:0) after 1m0s
[15:01:09.332] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:52:0)
[15:01:09.332] Timer tick, asking Buildbucket for the build status
[15:01:09.495] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:09.495] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:53:0) after 1m0s
[15:02:09.675] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:53:0)
[15:02:09.675] Timer tick, asking Buildbucket for the build status
[15:02:09.964] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:09.964] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:54:0) after 1m0s
[15:03:10.101] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:54:0)
[15:03:10.101] Timer tick, asking Buildbucket for the build status
[15:03:10.272] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:10.272] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:55:0) after 1m0s
[15:04:10.456] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:55:0)
[15:04:10.456] Timer tick, asking Buildbucket for the build status
[15:04:10.575] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:10.575] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:56:0) after 1m0s
[15:05:10.685] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:56:0)
[15:05:10.685] Timer tick, asking Buildbucket for the build status
[15:05:10.959] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:10.959] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:57:0) after 1m0s
[15:06:11.278] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:57:0)
[15:06:11.278] Timer tick, asking Buildbucket for the build status
[15:06:11.387] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:11.387] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:58:0) after 1m0s
[15:07:11.480] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:58:0)
[15:07:11.480] Timer tick, asking Buildbucket for the build status
[15:07:12.080] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:12.080] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:59:0) after 1m0s
[15:08:12.323] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:59:0)
[15:08:12.323] Timer tick, asking Buildbucket for the build status
[15:08:12.439] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:12.439] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:60:0) after 1m0s
[15:09:12.464] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:60:0)
[15:09:12.464] Timer tick, asking Buildbucket for the build status
[15:09:12.541] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:12.541] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:61:0) after 1m0s
[15:10:12.787] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:61:0)
[15:10:12.787] Timer tick, asking Buildbucket for the build status
[15:10:12.998] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:12.998] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:62:0) after 1m0s
[15:11:13.114] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:62:0)
[15:11:13.114] Timer tick, asking Buildbucket for the build status
[15:11:13.248] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:13.248] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:63:0) after 1m0s
[15:12:13.315] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:63:0)
[15:12:13.315] Timer tick, asking Buildbucket for the build status
[15:12:13.441] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:13.441] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:64:0) after 1m0s
[15:13:13.635] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:64:0)
[15:13:13.635] Timer tick, asking Buildbucket for the build status
[15:13:13.845] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:13.845] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:65:0) after 1m0s
[15:14:13.925] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:65:0)
[15:14:13.925] Timer tick, asking Buildbucket for the build status
[15:14:14.093] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:14.093] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:66:0) after 1m0s
[15:15:14.077] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:66:0)
[15:15:14.077] Timer tick, asking Buildbucket for the build status
[15:15:14.531] Build 8862561387287225664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:14.531] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027991449460870064:67:0) after 1m0s
[15:15:23.617] Received PubSub notification, asking Buildbucket for the build status
[15:15:23.883] Build 8862561387287225664: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[15:15:23.883] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1606403720996360",
"created_by": "project:chromium",
"created_ts": "1606399849578239",
"id": "8862561387287225664",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1c031986f763ebfdd9a6ee1c79cd36c70e805dc3\"}}",
"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\": \"1c031986f763ebfdd9a6ee1c79cd36c70e805dc3\", \"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-2gr3\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15864, \"clang_revision\": \"llvmorg-12-init-12083-g94e4ec64-1\", \"got_angle_revision\": \"c7a55f36b5f1f16ac7c645911e220ee2db6cedb8\", \"got_dawn_revision\": \"71279dcde7cd1b718b137b9ed0384803f347a359\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"1c031986f763ebfdd9a6ee1c79cd36c70e805dc3\", \"got_revision_cp\": \"refs/heads/master@{#831333}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"43bb60e1fa119d80b449c6550dd2b72328b101b9\", \"got_v8_revision\": \"0994d406d27080cbf0ba5c690553f5229dc0df6b\", \"got_v8_revision_cp\": \"refs/heads/8.9.57@{#1}\", \"got_webrtc_revision\": \"c9a09859fcc39480755e6095cf308d336b7e12e1\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32698}\", \"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\": \"1c031986f763ebfdd9a6ee1c79cd36c70e805dc3\", \"swarm_hashes_refs/heads/master(at){#831333}_without_patch\": {\"absl_hardening_tests\": \"4918cbf3c3edab1c87acb76a873882c9292f27b4\", \"accessibility_unittests\": \"3e6787aa4c8b515ab7468cb6a4ee86249bdb819e\", \"angle_unittests\": \"5546fd3b47832f326a447038a1fffc2b560412d6\", \"app_shell_unittests\": \"7664d1e1f1ecd52d93c9991fbcb6973bafe0888f\", \"aura_unittests\": \"f0dbf61ea6124f996960a3d6280852f36afdd705\", \"base_unittests\": \"5161eecab87ceb5d0c751706a14eaed083316e9d\", \"base_util_unittests\": \"f2faad506e8960d05b5a556e4601ca50481753c0\", \"blink_common_unittests\": \"36e07f7f779614c362a9aa64ac02b0c93f14cf92\", \"blink_fuzzer_unittests\": \"7d4cdf89a80a22d542fecaf83d321665c8fa18cc\", \"blink_heap_unittests\": \"932409152c1a6c7bf0062a642721a649baec3cc0\", \"blink_platform_unittests\": \"04de0c9dee4d7dcbf0d13aa4cc7a67df10ce188c\", \"blink_unittests\": \"c90fcf8706a2bad3073903bd8a7e61f856242719\", \"boringssl_crypto_tests\": \"0e827e55fc4e902fa13394e2f03e33411768509b\", \"boringssl_ssl_tests\": \"f5bafeaad5a9507e0f4a3d43b17cad33e6abed40\", \"browser_switcher_bho_unittests\": \"a5bb0313c3a39326e0b81dcd07d392ff5108f11d\", \"browser_tests\": \"e409429423ba59a2195e2b73e63236b620cf7a39\", \"capture_unittests\": \"db70063a852a65dc8af9b71e93a527cd4f80acdb\", \"cast_unittests\": \"cb7c33cf6cd43526cf28afa02096e518aea40f49\", \"cc_unittests\": \"745c875c63b04b642adfa69b1d2a5ea71bda8726\", \"chrome_app_unittests\": \"938ca5c7092d2e983120372a6d89f9aae87b7eca\", \"chrome_cleaner_unittests\": \"98d390066062242216f3656b8755940ebe9b53af\", \"chrome_elf_unittests\": \"9b7546e91b9f6cd02cb5d5fc6f5f737b483c2638\", \"chromedriver_unittests\": \"1f12694ddca6a5bb4e424b83fa5c588df6b6330e\", \"components_browsertests\": \"79994ad9ebb03056d79adc06b90137d51c984362\", \"components_unittests\": \"024865ae24797fc72771b086b7b7ce1f3377cb9a\", \"compositor_unittests\": \"1e56eca1788543b7f40d16a4291c37f6174bab85\", \"content_browsertests\": \"f2eb3f7cadbf438875c63cf9b4c6aa934e2ee289\", \"content_unittests\": \"b87ab6ce90d10bda3a01c4525df89b773d19cc3a\", \"courgette_unittests\": \"284223375255c857f179d5b5d744b7d8c7e0a7da\", \"crashpad_tests\": \"4911d21512ce5f37058f487327aac13a35b0f9b0\", \"cronet_tests\": \"54b472d33e8451fa4f3257aba70ce09fc49f8a87\", \"cronet_unittests\": \"1bf70783610d4292df3233518fc3519c004f4573\", \"crypto_unittests\": \"b60c8b269ab5cbd5d16515aed6b5c6eb80a9d8cf\", \"delayloads_unittests\": \"40760c3b6da4966042fe53fb470fbf7ae4be2af8\", \"device_unittests\": \"b37ef0dbdd53f6bab9595fd699c88f2dd8ba7e19\", \"display_unittests\": \"9550150e7146400c0c1ff64884e691e0f7b78861\", \"elevation_service_unittests\": \"bb1875cbdbbc54ade9f3145685fc6bc49064d3d8\", \"events_unittests\": \"6d1d7de607a1fe622d5c44b4294e40d59cffa606\", \"extensions_browsertests\": \"de184c4aab45dad108e40561ead5fe8b8370920c\", \"extensions_unittests\": \"1cae52739b58c9f4f103f32e4ac152592d413d67\", \"filesystem_service_unittests\": \"a9f9e7617bccdc1fd98da88c5146c0a3ab9e894e\", \"gcm_unit_tests\": \"08abd501b0c5b5bd2d384b7545f8d92d9c9bda4e\", \"gcp_unittests\": \"88e49f951c259c9238c3f33239f5b8e3ada59cf6\", \"gfx_unittests\": \"13d4fee93813f5a572ca53a8eb066f452f517c03\", \"gin_unittests\": \"4d8ab6acff4df75fbb3696c36153c54dcbb51819\", \"google_apis_unittests\": \"5d5d4aaa0c570311764d587cbe63a8ca27d98a35\", \"gpu_unittests\": \"33c11672600c830a16dd37d9a85c6b44ec1b1404\", \"gwp_asan_unittests\": \"442cc9d9695a33aa9e4b47cfe7691a62ac1cff6a\", \"headless_browsertests\": \"eaad77a45a9cf35c450f7cc292f6c2fc8d133ad0\", \"headless_unittests\": \"78e03201b0f6fae6e48038f5c577602514f5093e\", \"install_static_unittests\": \"299d718239d35cbe735db999f57ae89df5fdd449\", \"installer_util_unittests\": \"06b4ce97b08129ce6e2aec2e635fc5198b7e1f0f\", \"interactive_ui_tests\": \"7f8819e28656217a39c533894e5d649ea8ab6a40\", \"ipc_tests\": \"e77ce0d30e157e5ea79679b1aaf0fcce3ced4e53\", \"jingle_unittests\": \"4a110e877145e08e312fd6aecc3cfe5d0cb21b68\", \"latency_unittests\": \"40d25d0a26cb63d69bb8a442dc0fb81f832a5d29\", \"libjingle_xmpp_unittests\": \"759ec4fdee3ffbe0bbd6b1fd0ffdc33a1144b9a2\", \"liburlpattern_unittests\": \"addd143253a68b70bd267c328dc7b278ce399bdc\", \"media_blink_unittests\": \"f5f3754077163daec1fc4690b7245922521ca39e\", \"media_unittests\": \"9001bb95afc090610b9b74bf7790f2d24198654d\", \"message_center_unittests\": \"38b6496760e2bc23db88d8ba3d87c6a411084bf2\", \"midi_unittests\": \"a3ca27adf3bd4281c1d88b19df3887b3622adaaf\", \"mojo_core_unittests\": \"8f53b1c31ac6bb56abeae484e1c934caadfd284e\", \"mojo_unittests\": \"1a337bdc577c965ec287b4c4519ff80319cd01ed\", \"nacl_loader_unittests\": \"8e6b588be26543640817fb6df188518ee72d4452\", \"native_theme_unittests\": \"bd66cfbe73fd1a09096ba0f6b27567b7c5b1c9e6\", \"net_unittests\": \"5308ab86ed24ecf29c35a2938281e119cc260b3a\", \"notification_helper_unittests\": \"6d263a60628feb3ad9fd85c580c3cd30e0d90b4a\", \"pdf_unittests\": \"979167b99b5b046018a77adcb81122990b2a4b15\", \"perfetto_unittests\": \"06cc46c36cb35a66cef349ece22cb4a4df0ae921\", \"ppapi_unittests\": \"c57557704317ae9614630b4d4154808a7f6e3b6b\", \"printing_unittests\": \"3c63c20cc11162aa1b7a24217fe90cf23ce1d5b0\", \"remoting_unittests\": \"7a1e5bd7a2feebf7a735ef02861117152f8d11f5\", \"sbox_integration_tests\": \"3d05f8daa22b372a3695f6bba44e92f9c8ff2146\", \"sbox_unittests\": \"8ced15b44acad9236cb2cc4d34b0c9177a59a510\", \"sbox_validation_tests\": \"e774629355cfe75dc4a60b45f98235534d3c76ce\", \"service_manager_unittests\": \"f8323d17ecab2983efc6004fdb6f3c60a15e7e26\", \"services_unittests\": \"bfade7efd45dc725767055d37e40c3d26cfaae30\", \"setup_unittests\": \"6e6edb4375fd5e1a2799800959dd992ec46e004b\", \"shell_dialogs_unittests\": \"648ca6206e479839eb3006cc96ef123312a72992\", \"skia_unittests\": \"fe864ee3b6d0d0596b90eabac21ff934dc23600f\", \"snapshot_unittests\": \"4170e3521802410f52d9b1509392b0d621714b1e\", \"sql_unittests\": \"b8fc17e2e37ec3f190fc44c2997748515fe1d89e\", \"storage_unittests\": \"61924dacd4101c215b510a39fcbde64f13d6b93e\", \"sync_integration_tests\": \"52e0459c4a1297248c50a9676fec099942b4fecd\", \"traffic_annotation_auditor_unittests\": \"722f4c7bbe420316c069fa9428bb68c1ee7218cc\", \"ui_base_unittests\": \"3d4ddc4e11d0b343dcd841ab661d49cdd8b3fd08\", \"ui_touch_selection_unittests\": \"fc35e9f9741d23d81e19478e92ae3c334e822887\", \"unit_tests\": \"c62c81f9f645df8408064e9c742f09445aee0d59\", \"updater_tests\": \"44d05f29f4e147a79adfc2aff86df97e8c066240\", \"url_unittests\": \"df7e2dad1293c323f2e18a0b339b99bbd2ec33f8\", \"views_unittests\": \"65b4a79df388b10440394870cfa680328d519c5e\", \"viz_unittests\": \"4c1fa418dda2e81983ecee161f48259176c52f0e\", \"vr_common_unittests\": \"53e035931b5c1c7a788a988c3642e97f95ca09d6\", \"vr_pixeltests\": \"86203461519a031b345bb6430934f9da82580ef4\", \"weblayer_browsertests\": \"a4301224bed0df6ccde774c5ad8f3880be5241de\", \"weblayer_unittests\": \"228e2b65a1ed9347046bd98a1a4047339d0e3112\", \"wm_unittests\": \"a22396af49493cfb433e07700bd27f9bcf12fbb8\", \"wtf_unittests\": \"9dc2662c6c5d904f25709e6d8311f138ba69b126\", \"zlib_unittests\": \"ae081cdc6616c7d5495bc914ec29510c4239628f\", \"zucchini_unittests\": \"431fa5b1301e96b6cba4757387fc4406e0a98d86\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_ba2591630e3c492a7f8bed019e3d41c3ae1f8879793a1bf71c3be1367a2696d6_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-6-2gr3\"], \"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": "1606399853831372",
"status": "COMPLETED",
"status_changed_ts": "1606403721866347",
"tags": [
"build_address:luci.chromium.ci/win-asan/15864",
"builder:win-asan",
"buildset:commit/git/1c031986f763ebfdd9a6ee1c79cd36c70e805dc3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1c031986f763ebfdd9a6ee1c79cd36c70e805dc3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027991449460870064",
"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/8862561387287225664/+/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:501db604f2277e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1606403721921957",
"url": "https://ci.chromium.org/b/8862561387287225664",
"utcnow_ts": "1606403723870230"
}
}
[15:15:23.884] Invocation finished in 1h4m35.762370769s with status SUCCEEDED